[00:19:48.615] New invocation is queued and will start shortly
[00:19:49.727] Starting the invocation (attempt 1)
[00:19:49.775] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:19:49.775] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:19:49.775] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029312088491247696",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"90da28274bba56d20fc094ff96cd73731f29f86e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029312088491247696",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/90da28274bba56d20fc094ff96cd73731f29f86e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90da28274bba56d20fc094ff96cd73731f29f86e",
"gitiles_ref:refs/heads/master"
]
}
[00:19:50.673] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605140389898147",
"id": "8863882026480054672",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90da28274bba56d20fc094ff96cd73731f29f86e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605140390351021",
"tags": [
"build_address:luci.chromium.ci/win-asan/15596",
"builder:win-asan",
"buildset:commit/git/90da28274bba56d20fc094ff96cd73731f29f86e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90da28274bba56d20fc094ff96cd73731f29f86e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029312088491247696",
"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/8863882026480054672/+/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": "1605140390351131",
"url": "https://ci.chromium.org/b/8863882026480054672",
"utcnow_ts": "1605140390664522"
}
}
[00:19:50.673] Task URL: https://ci.chromium.org/b/8863882026480054672
[00:19:50.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:2:0) after 1m0s
[00:19:53.566] Received PubSub notification, asking Buildbucket for the build status
[00:19:53.691] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:50.731] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:2:0)
[00:20:50.731] Timer tick, asking Buildbucket for the build status
[00:20:50.892] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:50.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:4:0) after 1m0s
[00:21:50.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:4:0)
[00:21:50.920] Timer tick, asking Buildbucket for the build status
[00:21:51.031] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:51.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:5:0) after 1m0s
[00:22:51.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:5:0)
[00:22:51.059] Timer tick, asking Buildbucket for the build status
[00:22:51.153] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:51.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:6:0) after 1m0s
[00:23:51.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:6:0)
[00:23:51.176] Timer tick, asking Buildbucket for the build status
[00:23:51.275] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:51.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:7:0) after 1m0s
[00:24:51.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:7:0)
[00:24:51.340] Timer tick, asking Buildbucket for the build status
[00:24:51.454] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:51.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:8:0) after 1m0s
[00:25:52.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:8:0)
[00:25:52.655] Timer tick, asking Buildbucket for the build status
[00:25:52.830] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:52.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:9:0) after 1m0s
[00:26:52.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:9:0)
[00:26:52.906] Timer tick, asking Buildbucket for the build status
[00:26:53.527] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:53.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:10:0) after 1m0s
[00:27:53.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:10:0)
[00:27:53.573] Timer tick, asking Buildbucket for the build status
[00:27:53.669] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:53.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:11:0) after 1m0s
[00:28:53.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:11:0)
[00:28:53.728] Timer tick, asking Buildbucket for the build status
[00:28:53.905] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:53.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:12:0) after 1m0s
[00:29:53.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:12:0)
[00:29:53.962] Timer tick, asking Buildbucket for the build status
[00:29:54.209] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:54.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:13:0) after 1m0s
[00:30:54.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:13:0)
[00:30:54.249] Timer tick, asking Buildbucket for the build status
[00:30:54.332] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:54.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:14:0) after 1m0s
[00:31:54.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:14:0)
[00:31:54.403] Timer tick, asking Buildbucket for the build status
[00:31:54.579] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:54.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:15:0) after 1m0s
[00:32:54.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:15:0)
[00:32:54.649] Timer tick, asking Buildbucket for the build status
[00:32:54.798] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:54.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:16:0) after 1m0s
[00:33:54.789] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:16:0)
[00:33:54.789] Timer tick, asking Buildbucket for the build status
[00:33:54.888] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:54.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:17:0) after 1m0s
[00:34:54.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:17:0)
[00:34:54.917] Timer tick, asking Buildbucket for the build status
[00:34:55.068] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:55.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:18:0) after 1m0s
[00:35:55.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:18:0)
[00:35:55.116] Timer tick, asking Buildbucket for the build status
[00:35:55.186] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:55.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:19:0) after 1m0s
[00:36:55.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:19:0)
[00:36:55.218] Timer tick, asking Buildbucket for the build status
[00:36:55.297] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:55.297] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:20:0) after 1m0s
[00:37:55.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:20:0)
[00:37:55.321] Timer tick, asking Buildbucket for the build status
[00:37:55.524] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:55.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:21:0) after 1m0s
[00:38:55.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:21:0)
[00:38:55.580] Timer tick, asking Buildbucket for the build status
[00:38:55.743] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:55.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:22:0) after 1m0s
[00:39:55.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:22:0)
[00:39:55.770] Timer tick, asking Buildbucket for the build status
[00:39:55.893] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:55.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:23:0) after 1m0s
[00:40:55.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:23:0)
[00:40:55.922] Timer tick, asking Buildbucket for the build status
[00:40:56.035] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:56.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:24:0) after 1m0s
[00:41:56.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:24:0)
[00:41:56.074] Timer tick, asking Buildbucket for the build status
[00:41:56.651] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:56.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:25:0) after 1m0s
[00:42:56.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:25:0)
[00:42:56.708] Timer tick, asking Buildbucket for the build status
[00:42:56.864] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:56.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:26:0) after 1m0s
[00:43:56.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:26:0)
[00:43:56.929] Timer tick, asking Buildbucket for the build status
[00:43:57.061] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:57.061] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:27:0) after 1m0s
[00:44:57.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:27:0)
[00:44:57.097] Timer tick, asking Buildbucket for the build status
[00:44:57.489] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:57.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:28:0) after 1m0s
[00:45:57.512] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:28:0)
[00:45:57.512] Timer tick, asking Buildbucket for the build status
[00:45:57.947] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:57.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:29:0) after 1m0s
[00:46:57.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:29:0)
[00:46:57.941] Timer tick, asking Buildbucket for the build status
[00:46:58.391] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:58.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:30:0) after 1m0s
[00:47:58.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:30:0)
[00:47:58.418] Timer tick, asking Buildbucket for the build status
[00:47:58.550] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:58.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:31:0) after 1m0s
[00:48:58.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:31:0)
[00:48:58.573] Timer tick, asking Buildbucket for the build status
[00:48:58.698] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:58.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:32:0) after 1m0s
[00:49:58.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:32:0)
[00:49:58.765] Timer tick, asking Buildbucket for the build status
[00:49:58.907] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:58.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:33:0) after 1m0s
[00:50:58.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:33:0)
[00:50:58.958] Timer tick, asking Buildbucket for the build status
[00:50:59.027] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:59.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:34:0) after 1m0s
[00:51:59.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:34:0)
[00:51:59.065] Timer tick, asking Buildbucket for the build status
[00:51:59.213] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:59.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:35:0) after 1m0s
[00:52:59.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:35:0)
[00:52:59.294] Timer tick, asking Buildbucket for the build status
[00:52:59.475] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:59.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:36:0) after 1m0s
[00:53:59.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:36:0)
[00:53:59.506] Timer tick, asking Buildbucket for the build status
[00:53:59.629] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:59.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:37:0) after 1m0s
[00:54:59.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:37:0)
[00:54:59.667] Timer tick, asking Buildbucket for the build status
[00:55:00.205] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:00.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:38:0) after 1m0s
[00:56:00.283] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:38:0)
[00:56:00.283] Timer tick, asking Buildbucket for the build status
[00:56:00.358] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:00.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:39:0) after 1m0s
[00:57:00.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:39:0)
[00:57:00.465] Timer tick, asking Buildbucket for the build status
[00:57:00.555] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:00.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:40:0) after 1m0s
[00:58:00.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:40:0)
[00:58:00.549] Timer tick, asking Buildbucket for the build status
[00:58:00.632] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:00.632] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:41:0) after 1m0s
[00:59:00.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:41:0)
[00:59:00.661] Timer tick, asking Buildbucket for the build status
[00:59:00.932] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:00.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:42:0) after 1m0s
[01:00:04.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:42:0)
[01:00:04.149] Timer tick, asking Buildbucket for the build status
[01:00:04.599] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:04.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:43:0) after 1m0s
[01:01:04.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:43:0)
[01:01:04.650] Timer tick, asking Buildbucket for the build status
[01:01:04.845] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:04.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:44:0) after 1m0s
[01:02:05.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:44:0)
[01:02:05.575] Timer tick, asking Buildbucket for the build status
[01:02:06.306] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:06.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:45:0) after 1m0s
[01:03:06.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:45:0)
[01:03:06.348] Timer tick, asking Buildbucket for the build status
[01:03:06.700] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:06.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:46:0) after 1m0s
[01:04:06.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:46:0)
[01:04:06.751] Timer tick, asking Buildbucket for the build status
[01:04:06.918] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:06.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:47:0) after 1m0s
[01:05:06.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:47:0)
[01:05:06.947] Timer tick, asking Buildbucket for the build status
[01:05:07.311] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:07.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:48:0) after 1m0s
[01:06:07.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:48:0)
[01:06:07.434] Timer tick, asking Buildbucket for the build status
[01:06:08.001] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:08.001] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:49:0) after 1m0s
[01:07:08.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:49:0)
[01:07:08.112] Timer tick, asking Buildbucket for the build status
[01:07:08.273] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:08.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:50:0) after 1m0s
[01:08:08.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:50:0)
[01:08:08.386] Timer tick, asking Buildbucket for the build status
[01:08:08.654] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:08.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:51:0) after 1m0s
[01:09:08.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:51:0)
[01:09:08.765] Timer tick, asking Buildbucket for the build status
[01:09:09.100] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:09.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:52:0) after 1m0s
[01:10:09.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:52:0)
[01:10:09.129] Timer tick, asking Buildbucket for the build status
[01:10:09.317] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:09.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:53:0) after 1m0s
[01:11:09.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:53:0)
[01:11:09.413] Timer tick, asking Buildbucket for the build status
[01:11:09.551] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:09.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:54:0) after 1m0s
[01:12:09.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:54:0)
[01:12:09.618] Timer tick, asking Buildbucket for the build status
[01:12:09.782] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:09.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:55:0) after 1m0s
[01:13:09.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:55:0)
[01:13:09.836] Timer tick, asking Buildbucket for the build status
[01:13:09.924] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:09.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:56:0) after 1m0s
[01:14:10.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:56:0)
[01:14:10.031] Timer tick, asking Buildbucket for the build status
[01:14:10.479] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:10.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:57:0) after 1m0s
[01:15:10.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:57:0)
[01:15:10.551] Timer tick, asking Buildbucket for the build status
[01:15:10.723] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:10.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:58:0) after 1m0s
[01:16:10.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:58:0)
[01:16:10.770] Timer tick, asking Buildbucket for the build status
[01:16:11.095] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:11.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:59:0) after 1m0s
[01:17:11.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:59:0)
[01:17:11.161] Timer tick, asking Buildbucket for the build status
[01:17:11.324] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:11.324] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:60:0) after 1m0s
[01:18:11.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:60:0)
[01:18:11.408] Timer tick, asking Buildbucket for the build status
[01:18:11.926] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:11.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:61:0) after 1m0s
[01:19:11.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:61:0)
[01:19:11.984] Timer tick, asking Buildbucket for the build status
[01:19:12.449] Build 8863882026480054672: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:12.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029312088491247696:62:0) after 1m0s
[01:19:38.342] Received PubSub notification, asking Buildbucket for the build status
[01:19:38.421] Build 8863882026480054672: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:19:38.421] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605143976951409",
"created_by": "project:chromium",
"created_ts": "1605140389898147",
"id": "8863882026480054672",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"90da28274bba56d20fc094ff96cd73731f29f86e\"}}",
"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\": \"90da28274bba56d20fc094ff96cd73731f29f86e\", \"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-4-h4bf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15596, \"clang_revision\": \"llvmorg-12-init-11060-g118c3f3c-1\", \"got_angle_revision\": \"15d5965d77c5a4c23fedb22dd35837312d39e563\", \"got_dawn_revision\": \"3120d5ea0de8840298cc1dd3e702a1a3ebfe9523\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"90da28274bba56d20fc094ff96cd73731f29f86e\", \"got_revision_cp\": \"refs/heads/master@{#826554}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e1012cfc791ddbc6ce11e319831da7973b9a5b32\", \"got_v8_revision\": \"90a57536166aad8480e2252806dbc6b2e843bd77\", \"got_v8_revision_cp\": \"refs/heads/8.8.288@{#1}\", \"got_webrtc_revision\": \"06bbeb3398afa8651ca76ea7eb56b7d046edfca8\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32588}\", \"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\": \"90da28274bba56d20fc094ff96cd73731f29f86e\", \"swarm_hashes_refs/heads/master(at){#826554}_without_patch\": {\"absl_hardening_tests\": \"c749b3c66a9c29717b9d551e27ad5f05d1b687b9\", \"accessibility_unittests\": \"5fd0dcaf07d8340828b4c5543d70bc6d0ff3b66c\", \"angle_unittests\": \"1c25657c84e90b9477442c747b0dc4afbae3a249\", \"app_shell_unittests\": \"2d6c938c2ed50a5dc7724a508c69626da1747dbf\", \"aura_unittests\": \"368777d11e9d7d115e53c68e2298acd6ea900f2f\", \"base_unittests\": \"1424a878655f1877573d85638c94d5969bfb6a7b\", \"base_util_unittests\": \"78d5f43d364486858d18dce5792e6122fba9382b\", \"blink_common_unittests\": \"9ac74f5cb6bb5b90a6144a0ab64411efedc752a5\", \"blink_fuzzer_unittests\": \"66a23f5a34ca79770ceff3628b140be0db77a523\", \"blink_heap_unittests\": \"e7ec9c9df95c10cc5df9545e8e8909b8162cff92\", \"blink_platform_unittests\": \"fa07b13a7b6408dc248ee26162637e6220f08aba\", \"blink_unittests\": \"16fb4c2a11fd918f51430f73bc58fdcd113b13db\", \"boringssl_crypto_tests\": \"36e01def083acddd9fa2a2362fe16d58c3e708af\", \"boringssl_ssl_tests\": \"58f65084927492f34bede92d45058fb602e81153\", \"browser_switcher_bho_unittests\": \"b15ca9238dc22efe07b7fb64bf3750fd330eb2c4\", \"browser_tests\": \"ba8df375685f0669b59edefe939abf5db6e6fe08\", \"capture_unittests\": \"7d23f1f9ce03f49d3825de4fcd54b10939e10537\", \"cast_unittests\": \"b76f9a509e29280ad074a6b2b1b07823996bb949\", \"cc_unittests\": \"237a39699a2ed6b25f3231e2b728f589730347b9\", \"chrome_app_unittests\": \"76e80ae18ac2ad377d592a5a236ca7aabd2ad669\", \"chrome_cleaner_unittests\": \"3eb62d762cd77737b6bbe232a0852831f40d2f99\", \"chrome_elf_unittests\": \"22470213b6a46ceb354a296efd3736e60843769e\", \"chromedriver_unittests\": \"c61fb01976342194b12ca12f0a4b68af03232c55\", \"components_browsertests\": \"215b0210583b2ea0031145a93508b928816ced05\", \"components_unittests\": \"41beaa7fae3e1b7cdb41e737a44d0c1cd6c33dd3\", \"compositor_unittests\": \"afe9ef5a5e9ac42e714d4e409aacfb78bfca6e12\", \"content_browsertests\": \"eb995f3af307b4800f78eb6a128e26a0b9bbd469\", \"content_unittests\": \"c2b1d93f3606e1074a7d8c0b89d059c68366fba4\", \"courgette_unittests\": \"fa6fd37ac8390802793411a7dcedc8d3fe51e6a8\", \"crashpad_tests\": \"d565b423ba56d1860e696c2d74b9d5171f062d2a\", \"cronet_tests\": \"7401a3287cc22e43e3da4bdb3e0b0acdb6187fa0\", \"cronet_unittests\": \"50c38cfde5d626235aa3cf515ae927f6a3f5ddd0\", \"crypto_unittests\": \"75f9bfdf4d4a30331cdf2524c9a6250066cfb920\", \"delayloads_unittests\": \"d16b9bd04d5508def5e9f14078940c19388330b6\", \"device_unittests\": \"3ec8315e0cf940207c5f5a51c2e9b562be87b9a8\", \"display_unittests\": \"b22017ff423cd8fd70bd9bf75febb8634b480d18\", \"elevation_service_unittests\": \"7edd61ac9a4c08352bac6a4a33d2dcef32296e72\", \"events_unittests\": \"3c73a494d0664625b3cff3cb7298f0e8d9b06cf2\", \"extensions_browsertests\": \"1ce40986376d61818bb844bcc02ad246e7710a10\", \"extensions_unittests\": \"31f7154041e8da927d6152b9b8605bb076e1089e\", \"filesystem_service_unittests\": \"b41a394821aae8c83858b40264a71b663048dafa\", \"gcm_unit_tests\": \"a15f2eecea561c0ae84007d592561616cb5ffeed\", \"gcp_unittests\": \"0bf10becb12e362be8bdac25be8bb1881a390e2d\", \"gfx_unittests\": \"e9b424a0426288e260916b40d2c3b3fbd8eecc2c\", \"gin_unittests\": \"bf1f6f9c82ca81af035729cf1d76328887d5b9b6\", \"google_apis_unittests\": \"c700ccc36ed61faa850d5ea3c4d1b9068c9c4be0\", \"gpu_unittests\": \"24cb3df70554af1ea6d5921bb360d7535017c246\", \"gwp_asan_unittests\": \"879bb2d49123bea84cbbd511b8eb1e17b1bb102f\", \"headless_browsertests\": \"3b1445a9b29512faf39cb8078a85145b240e1979\", \"headless_unittests\": \"7a028d6827d54d6adfda0e1d9aef75b42867dacc\", \"install_static_unittests\": \"048042f43a413f3b65ea2823b5ae75633b45f4b7\", \"installer_util_unittests\": \"b15395a05b5e8a5597eccae1498321e3016cd3d1\", \"interactive_ui_tests\": \"77fe7258f05a147cc130e80b5d571f7b57e9cd73\", \"ipc_tests\": \"085eb11ab4d4db67a73db6639c63211c8bdf6fca\", \"jingle_unittests\": \"56ca89cda164e211f5b85df7f7b1aad48e747f78\", \"latency_unittests\": \"8b894f748dcd97aa014ff1c57c93dcf2215e029c\", \"libjingle_xmpp_unittests\": \"71cc0481a021ff064864cde4258e39898c1ffd13\", \"liburlpattern_unittests\": \"83ff6408c5538a63e400b86840610c306d3de854\", \"media_blink_unittests\": \"95cbeef104db39e5951eb69dfc0ff81c1c30fd66\", \"media_unittests\": \"355e60304730c30def8d54b24d6e8d18de6cbdd1\", \"message_center_unittests\": \"e7d1c2ede61a073db4c703bb275dff42d1ef797f\", \"midi_unittests\": \"448c6c1d8dc33dde8ad4230572ada54e2f1c6dc1\", \"mojo_core_unittests\": \"d05a2f6f6270601725198321f754a766d0afbb21\", \"mojo_unittests\": \"e1151db899c9b3ba2790664ef6606b99368fa395\", \"nacl_loader_unittests\": \"05335191929c6eb388b394dc134effa380d94f74\", \"native_theme_unittests\": \"2f6dfe3c3871202f7c6664ced5a9a9c314935322\", \"net_unittests\": \"1749ef140aa9f3685cf166945c44e126e8ef6f60\", \"notification_helper_unittests\": \"8019137bd8464bc2160b5596e58b2dc010dcee4f\", \"pdf_unittests\": \"e12c422c1a8ed611b13c1b7b7efae535c207d247\", \"perfetto_unittests\": \"314884cf1d8e3a454a770256de53c2bf2a191969\", \"ppapi_unittests\": \"439e32ce0434b669d58cf405ea83f842645c0057\", \"printing_unittests\": \"fdd474031c05c29ade653fa89cbf10dd280a274c\", \"remoting_unittests\": \"cf5382a6f580d5f9ceb958774f27851fad9515e9\", \"sbox_integration_tests\": \"ee1cccb5bed474e073f2ebd7bfa88e4081143663\", \"sbox_unittests\": \"cc9d6f1a29f2f73bb37f8724f6c4cd36eff747ef\", \"sbox_validation_tests\": \"32738896a2a23526c0257d661533ac84f3c8152a\", \"service_manager_unittests\": \"4c11e3df2993eed359b3c1ebd616275ffb367ae3\", \"services_unittests\": \"28a163b422ea1905ac81fe3ed161f2f6630a808b\", \"setup_unittests\": \"728834c98cc80215123da37f3158900c318943dd\", \"shell_dialogs_unittests\": \"ee97d15736e0ecd38a7e411cba155c9618521c9c\", \"skia_unittests\": \"d4f7e4a753bc4a91bb54440df129ffbce9b288f9\", \"snapshot_unittests\": \"874f68c3284bb5b2519a54c74c6a1a320bf2d83d\", \"sql_unittests\": \"54ddf871319eddaf71cf199c8d81532fbefc5481\", \"storage_unittests\": \"bde9ffcf83a0019b3bd687a52148a6922a865487\", \"sync_integration_tests\": \"3fde9f4c83a5373b73afaca62e3b06b296c5eb8c\", \"traffic_annotation_auditor_unittests\": \"309c83a2fcd4db97595e04cb8b46cb5c30c5a8a0\", \"ui_base_unittests\": \"d48167cd3aaae78eef836e007737daf41c3ddb44\", \"ui_touch_selection_unittests\": \"69ccb9f3d9b9366610bbeed6589b450f4a81df22\", \"unit_tests\": \"92e6426aecb4a7961ddc1a870cc1698b65ef55ee\", \"updater_tests\": \"8fce2a9ad7c7bc5dcdd2718fd8c784652739b866\", \"url_unittests\": \"2776976d4625ab7d4ef513be166a537676584e30\", \"views_unittests\": \"f69ab962d35e76edbef62b4207b1c2b20f1f4f0e\", \"viz_unittests\": \"b634fb21d7c6937b31f20c624f7884f09122112b\", \"vr_common_unittests\": \"b672a96aa5f5968fce9dcc3374c628f6daf1028b\", \"vr_pixeltests\": \"93fcea7417751c7d115c5c4b73fea937b7882987\", \"weblayer_browsertests\": \"8c7af190f095ed5e65e71800bd8df12515b03564\", \"weblayer_unittests\": \"3b79ce3526351ea31ac4ab4c41f14c04350fb696\", \"wm_unittests\": \"784fb1019117dd25f717ef35982ac1b404b083db\", \"wtf_unittests\": \"746c0eda95463ff3a805237231d29b2aea3c956b\", \"zlib_unittests\": \"57501f1a2a27676f5a3766bacbf2e42ce1258b14\", \"zucchini_unittests\": \"104e0e436aea7ad2e19e80f0c0c3845df5c56fb0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"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-4-h4bf\"], \"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\": [\"5626-39642e9\"], \"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": "1605140392539713",
"status": "COMPLETED",
"status_changed_ts": "1605143977338044",
"tags": [
"build_address:luci.chromium.ci/win-asan/15596",
"builder:win-asan",
"buildset:commit/git/90da28274bba56d20fc094ff96cd73731f29f86e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/90da28274bba56d20fc094ff96cd73731f29f86e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029312088491247696",
"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/8863882026480054672/+/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:4fd2a42b44da4910",
"user_agent:luci-scheduler"
],
"updated_ts": "1605143977356346",
"url": "https://ci.chromium.org/b/8863882026480054672",
"utcnow_ts": "1605143978408617"
}
}
[01:19:38.421] Invocation finished in 59m49.823669771s with status SUCCEEDED