[12:05:32.385] New invocation is queued and will start shortly
[12:05:33.547] Starting the invocation (attempt 1)
[12:05:33.587] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:05:33.589] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:05:33.589] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023560078945178144",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"99127a0c0a7320fea49f63627931bdc09c6eb3f7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023560078945178144",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/99127a0c0a7320fea49f63627931bdc09c6eb3f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99127a0c0a7320fea49f63627931bdc09c6eb3f7",
"gitiles_ref:refs/heads/master"
]
}
[12:05:34.321] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610625933681629",
"id": "8858130016922596720",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"99127a0c0a7320fea49f63627931bdc09c6eb3f7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610625934148341",
"tags": [
"build_address:luci.chromium.ci/win-asan/16815",
"builder:win-asan",
"buildset:commit/git/99127a0c0a7320fea49f63627931bdc09c6eb3f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99127a0c0a7320fea49f63627931bdc09c6eb3f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023560078945178144",
"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/8858130016922596720/+/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": "1610625934148447",
"url": "https://ci.chromium.org/b/8858130016922596720",
"utcnow_ts": "1610625934313074"
}
}
[12:05:34.321] Task URL: https://ci.chromium.org/b/8858130016922596720
[12:05:34.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:2:0) after 1m0s
[12:05:37.043] Received PubSub notification, asking Buildbucket for the build status
[12:05:37.397] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:34.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:2:0)
[12:06:34.346] Timer tick, asking Buildbucket for the build status
[12:06:34.410] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:06:34.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:4:0) after 1m0s
[12:07:34.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:4:0)
[12:07:34.404] Timer tick, asking Buildbucket for the build status
[12:07:34.507] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:07:34.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:5:0) after 1m0s
[12:08:34.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:5:0)
[12:08:34.563] Timer tick, asking Buildbucket for the build status
[12:08:35.095] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:08:35.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:6:0) after 1m0s
[12:09:35.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:6:0)
[12:09:35.162] Timer tick, asking Buildbucket for the build status
[12:09:35.283] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:09:35.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:7:0) after 1m0s
[12:10:35.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:7:0)
[12:10:35.306] Timer tick, asking Buildbucket for the build status
[12:10:35.386] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:35.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:8:0) after 1m0s
[12:11:35.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:8:0)
[12:11:35.409] Timer tick, asking Buildbucket for the build status
[12:11:35.589] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:35.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:9:0) after 1m0s
[12:12:35.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:9:0)
[12:12:35.671] Timer tick, asking Buildbucket for the build status
[12:12:35.922] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:35.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:10:0) after 1m0s
[12:13:35.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:10:0)
[12:13:35.946] Timer tick, asking Buildbucket for the build status
[12:13:36.131] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:36.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:11:0) after 1m0s
[12:14:36.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:11:0)
[12:14:36.154] Timer tick, asking Buildbucket for the build status
[12:14:36.686] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:36.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:12:0) after 1m0s
[12:15:36.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:12:0)
[12:15:36.739] Timer tick, asking Buildbucket for the build status
[12:15:36.891] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:36.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:13:0) after 1m0s
[12:16:37.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:13:0)
[12:16:37.843] Timer tick, asking Buildbucket for the build status
[12:16:38.204] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:38.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:14:0) after 1m0s
[12:17:38.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:14:0)
[12:17:38.222] Timer tick, asking Buildbucket for the build status
[12:17:38.486] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:38.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:15:0) after 1m0s
[12:18:38.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:15:0)
[12:18:38.507] Timer tick, asking Buildbucket for the build status
[12:18:38.608] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:38.608] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:16:0) after 1m0s
[12:19:38.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:16:0)
[12:19:38.635] Timer tick, asking Buildbucket for the build status
[12:19:38.784] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:38.784] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:17:0) after 1m0s
[12:20:38.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:17:0)
[12:20:38.851] Timer tick, asking Buildbucket for the build status
[12:20:39.058] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:39.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:18:0) after 1m0s
[12:21:39.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:18:0)
[12:21:39.089] Timer tick, asking Buildbucket for the build status
[12:21:39.210] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:39.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:19:0) after 1m0s
[12:22:39.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:19:0)
[12:22:39.223] Timer tick, asking Buildbucket for the build status
[12:22:39.540] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:39.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:20:0) after 1m0s
[12:23:39.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:20:0)
[12:23:39.609] Timer tick, asking Buildbucket for the build status
[12:23:39.748] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:39.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:21:0) after 1m0s
[12:24:39.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:21:0)
[12:24:39.832] Timer tick, asking Buildbucket for the build status
[12:24:39.909] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:39.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:22:0) after 1m0s
[12:25:40.006] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:22:0)
[12:25:40.006] Timer tick, asking Buildbucket for the build status
[12:25:40.122] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:40.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:23:0) after 1m0s
[12:26:40.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:23:0)
[12:26:40.171] Timer tick, asking Buildbucket for the build status
[12:26:40.869] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:40.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:24:0) after 1m0s
[12:27:40.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:24:0)
[12:27:40.905] Timer tick, asking Buildbucket for the build status
[12:27:41.027] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:41.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:25:0) after 1m0s
[12:28:41.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:25:0)
[12:28:41.024] Timer tick, asking Buildbucket for the build status
[12:28:41.124] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:41.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:26:0) after 1m0s
[12:29:41.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:26:0)
[12:29:41.146] Timer tick, asking Buildbucket for the build status
[12:29:41.378] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:41.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:27:0) after 1m0s
[12:30:41.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:27:0)
[12:30:41.430] Timer tick, asking Buildbucket for the build status
[12:30:41.577] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:41.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:28:0) after 1m0s
[12:31:41.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:28:0)
[12:31:41.604] Timer tick, asking Buildbucket for the build status
[12:31:42.415] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:42.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:29:0) after 1m0s
[12:32:42.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:29:0)
[12:32:42.518] Timer tick, asking Buildbucket for the build status
[12:32:42.600] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:42.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:30:0) after 1m0s
[12:33:42.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:30:0)
[12:33:42.703] Timer tick, asking Buildbucket for the build status
[12:33:42.935] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:42.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:31:0) after 1m0s
[12:34:42.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:31:0)
[12:34:42.967] Timer tick, asking Buildbucket for the build status
[12:34:43.122] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:43.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:32:0) after 1m0s
[12:35:43.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:32:0)
[12:35:43.221] Timer tick, asking Buildbucket for the build status
[12:35:43.561] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:43.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:33:0) after 1m0s
[12:36:43.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:33:0)
[12:36:43.544] Timer tick, asking Buildbucket for the build status
[12:36:43.870] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:43.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:34:0) after 1m0s
[12:37:43.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:34:0)
[12:37:43.927] Timer tick, asking Buildbucket for the build status
[12:37:44.069] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:44.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:35:0) after 1m0s
[12:38:44.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:35:0)
[12:38:44.092] Timer tick, asking Buildbucket for the build status
[12:38:44.505] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:44.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:36:0) after 1m0s
[12:39:44.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:36:0)
[12:39:44.574] Timer tick, asking Buildbucket for the build status
[12:39:44.894] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:44.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:37:0) after 1m0s
[12:40:45.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:37:0)
[12:40:45.003] Timer tick, asking Buildbucket for the build status
[12:40:45.505] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:45.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:38:0) after 1m0s
[12:41:45.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:38:0)
[12:41:45.553] Timer tick, asking Buildbucket for the build status
[12:41:45.700] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:45.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:39:0) after 1m0s
[12:42:45.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:39:0)
[12:42:45.806] Timer tick, asking Buildbucket for the build status
[12:42:45.877] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:45.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:40:0) after 1m0s
[12:43:45.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:40:0)
[12:43:45.970] Timer tick, asking Buildbucket for the build status
[12:43:46.109] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:46.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:41:0) after 1m0s
[12:44:46.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:41:0)
[12:44:46.105] Timer tick, asking Buildbucket for the build status
[12:44:46.224] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:46.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:42:0) after 1m0s
[12:45:46.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:42:0)
[12:45:46.325] Timer tick, asking Buildbucket for the build status
[12:45:46.720] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:46.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:43:0) after 1m0s
[12:46:46.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:43:0)
[12:46:46.743] Timer tick, asking Buildbucket for the build status
[12:46:47.168] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:47.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:44:0) after 1m0s
[12:47:47.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:44:0)
[12:47:47.280] Timer tick, asking Buildbucket for the build status
[12:47:48.713] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:48.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:45:0) after 1m0s
[12:48:48.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:45:0)
[12:48:48.813] Timer tick, asking Buildbucket for the build status
[12:48:48.941] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:48.941] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:46:0) after 1m0s
[12:49:48.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:46:0)
[12:49:48.991] Timer tick, asking Buildbucket for the build status
[12:49:49.211] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:49.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:47:0) after 1m0s
[12:50:49.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:47:0)
[12:50:49.264] Timer tick, asking Buildbucket for the build status
[12:50:49.574] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:49.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:48:0) after 1m0s
[12:51:49.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:48:0)
[12:51:49.682] Timer tick, asking Buildbucket for the build status
[12:51:49.800] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:49.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:49:0) after 1m0s
[12:52:49.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:49:0)
[12:52:49.983] Timer tick, asking Buildbucket for the build status
[12:52:50.074] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:50.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:50:0) after 1m0s
[12:53:50.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:50:0)
[12:53:50.079] Timer tick, asking Buildbucket for the build status
[12:53:51.185] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:51.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:51:0) after 1m0s
[12:54:51.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:51:0)
[12:54:51.579] Timer tick, asking Buildbucket for the build status
[12:54:51.927] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:51.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:52:0) after 1m0s
[12:55:52.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:52:0)
[12:55:52.052] Timer tick, asking Buildbucket for the build status
[12:55:52.182] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:52.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:53:0) after 1m0s
[12:56:52.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:53:0)
[12:56:52.198] Timer tick, asking Buildbucket for the build status
[12:56:52.316] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:52.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:54:0) after 1m0s
[12:57:52.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:54:0)
[12:57:52.330] Timer tick, asking Buildbucket for the build status
[12:57:52.488] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:52.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:55:0) after 1m0s
[12:58:52.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:55:0)
[12:58:52.559] Timer tick, asking Buildbucket for the build status
[12:58:53.115] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:53.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:56:0) after 1m0s
[12:59:53.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:56:0)
[12:59:53.137] Timer tick, asking Buildbucket for the build status
[12:59:53.573] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:53.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:57:0) after 1m0s
[13:00:53.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:57:0)
[13:00:53.644] Timer tick, asking Buildbucket for the build status
[13:00:53.815] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:53.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:58:0) after 1m0s
[13:01:53.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:58:0)
[13:01:53.787] Timer tick, asking Buildbucket for the build status
[13:01:54.017] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:54.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:59:0) after 1m0s
[13:02:54.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:59:0)
[13:02:54.099] Timer tick, asking Buildbucket for the build status
[13:02:54.218] Build 8858130016922596720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:54.218] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023560078945178144:60:0) after 1m0s
[13:02:58.621] Received PubSub notification, asking Buildbucket for the build status
[13:02:58.704] Build 8858130016922596720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:02:58.704] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610629377830652",
"created_by": "project:chromium",
"created_ts": "1610625933681629",
"id": "8858130016922596720",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"99127a0c0a7320fea49f63627931bdc09c6eb3f7\"}}",
"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\": \"99127a0c0a7320fea49f63627931bdc09c6eb3f7\", \"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-3-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16815, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"b421774ae9c3a78ca833a506ce1ea3baa01c3bbe\", \"got_dawn_revision\": \"6f833b7f78c92595d3ec74ee622dab1868fdf40b\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"99127a0c0a7320fea49f63627931bdc09c6eb3f7\", \"got_revision_cp\": \"refs/heads/master@{#843492}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"5255c465bbbab020b2fa98f818a705e764865869\", \"got_v8_revision\": \"c80a50a3c4de747d61e044cea6f94223016110d2\", \"got_v8_revision_cp\": \"refs/heads/8.9.273@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"99127a0c0a7320fea49f63627931bdc09c6eb3f7\", \"swarm_hashes_refs/heads/master(at){#843492}_without_patch\": {\"absl_hardening_tests\": \"e5d200530756ee63183975eb05cece6b271f0d95\", \"accessibility_unittests\": \"e98b273deff3c5bf310b7046b16f0c2facbb15c6\", \"angle_unittests\": \"fa9cee359bb98e6df1e537fe9b48a8295a3f30af\", \"app_shell_unittests\": \"0f8c5a370119dd0235aa12cb9c0ebf8ea9e08e8f\", \"aura_unittests\": \"539c0482b0878b6318b2613224feccf94f5b14b1\", \"base_unittests\": \"5fddea7a7e1a242fa75378539c8f11faee9f8c80\", \"base_util_unittests\": \"262151d1d7b6f11e1333516b147d64315a589055\", \"blink_common_unittests\": \"c5813b9d56fa8a4cb98313cdac6bd4ca75212af7\", \"blink_fuzzer_unittests\": \"7a09cd5a14dad3c369ebc00dcd1b10f89f4b9368\", \"blink_heap_unittests\": \"85922877154855f7255eb6ed6f09a9fb248ae99b\", \"blink_platform_unittests\": \"e8e5a8ab30f118653c0b9e8f30a027d501e12ce7\", \"blink_unittests\": \"dd70e904cb26adbd9268a2c1c6e5a5fb2542b847\", \"boringssl_crypto_tests\": \"4d55d3433385cd277560b7b757b8856f144c9052\", \"boringssl_ssl_tests\": \"f4a74d0719257cd8a75f0fc3203113baa17c1708\", \"browser_switcher_bho_unittests\": \"2140e8c5d7e45c625b12f9c245eda90eb093d4e9\", \"browser_tests\": \"a3caec573af055562967be818b47d9e014c1f22c\", \"capture_unittests\": \"272ac9f2384ecab5c58a2456f9e872f312e72865\", \"cast_unittests\": \"8cdcd3e0fa78d6d0931d79ab18d688797a34b351\", \"cc_unittests\": \"3caf59286fd1d69a8822489c4ca3e9375d6aa9b7\", \"chrome_app_unittests\": \"857959e49e8c108b91a258ea8feb13788cbf9990\", \"chrome_cleaner_unittests\": \"ebb65a3c205088022c8ea274168ca687dd68352a\", \"chrome_elf_unittests\": \"5038d8444518e12138794a24b74455d9443102a4\", \"chromedriver_unittests\": \"9aa0f66cf6fa5f2f0218a58a39c73749ec44b9db\", \"components_browsertests\": \"7519b4a7a76c59bb5979029e5816afd4b3c62ba8\", \"components_unittests\": \"a98d2d2c6751c96e5582588e6dd0b7f1e3a14e9a\", \"compositor_unittests\": \"3cdea77bcdbc1a53d9c4fd5c24a91ef76210ce6d\", \"content_browsertests\": \"c8b9e8e0ad428b5a87e0ddbd09e46ec136ccc4d8\", \"content_unittests\": \"ebc230870de6cc52101ebddce5c3891610658e7a\", \"courgette_unittests\": \"5b36c05fbf6b6a0daaeb02ba86575ec3a7f6186e\", \"crashpad_tests\": \"274aaa96e55b178c72ba7f6ea60b2494a08f27b7\", \"cronet_tests\": \"3d72c0300c84e8036ae3b3c75f7151a2b94bd4f7\", \"cronet_unittests\": \"5a5cc0454f2cd62813f22fad29baa50e4c30f6bc\", \"crypto_unittests\": \"13e3b845582a26f4f7e63923560370dc444e7ff9\", \"delayloads_unittests\": \"cf71d39201ef104b9dbff76f0095b90f6591bdae\", \"device_unittests\": \"5842f361ee502bdf77dd5042dcfebe360d510963\", \"display_unittests\": \"0b16569f1432aaa0fa4665ff271d1e39439dc44f\", \"elevation_service_unittests\": \"0154cf07c74d4c0fa34f94ee08a63e7d673ddaf6\", \"events_unittests\": \"3bd6350d2b9a168f0911d3ca2a0b2a3ad1126376\", \"extensions_browsertests\": \"f0ed60c8affe1342d632b877cd2ad23d6ea527f6\", \"extensions_unittests\": \"7c81eca9ab6be0dfe37e3fb787af3110f0dd4159\", \"filesystem_service_unittests\": \"519f37d0a276c7c759a96edb1d035087db6e484f\", \"gcm_unit_tests\": \"542708573e98fe59544681bdabaeeae4b12fb47e\", \"gcp_unittests\": \"db1b5393a75791ce7b030ea15764437eefb5aa77\", \"gfx_unittests\": \"3344ec1118da5b600ffa5995a201d85513ca719b\", \"gin_unittests\": \"db00921617e1fd9e2ec4a2b8cca1d56f3c9cdb2b\", \"google_apis_unittests\": \"e7d02536f42b85b87b0c7a40be20abdb175c17ab\", \"gpu_unittests\": \"dbfe40a1bb83165cc0652da6f75e6b6a5eaadab8\", \"gwp_asan_unittests\": \"0edb5e62ab3bf0fd55d1879597645b04f8f920c5\", \"headless_browsertests\": \"035721c31724598fd54644818ba1cd15c78cbfa6\", \"headless_unittests\": \"1f28e4f92ca90f26427b89cba6450394c43a732c\", \"install_static_unittests\": \"b34655fa879dd19a7c852488e9794b5945dff31c\", \"installer_util_unittests\": \"37d23773e7540d9d34810340eb6eca481e00c382\", \"interactive_ui_tests\": \"3e81e580bfd6f22f31d3b7a4759452c2a6e9ee18\", \"ipc_tests\": \"b60cd4e6dbfbc7ff6d3f338130a4696741c18712\", \"jingle_unittests\": \"5d15934670ec8d146d05a79f40944b56dba1cc06\", \"latency_unittests\": \"1b765adf3581700311a0913d4c0ebdd9489f1a9c\", \"libjingle_xmpp_unittests\": \"28d9f75b640c56f0722f94da46fdeda8bac30f37\", \"liburlpattern_unittests\": \"6d7aa038f5e23f9350f241bfca4beaaea063e019\", \"media_blink_unittests\": \"99f995b39d7fad0747d7c4ea595247f9880201ce\", \"media_unittests\": \"1f632da2596564356a818a684c3256b19858eca1\", \"message_center_unittests\": \"4abfc559b328ad60ba51948e9f3a99fb626e675c\", \"midi_unittests\": \"92cb49e04ad7f2c8f57717ca582fe552b8040360\", \"mojo_core_unittests\": \"cc2d1f8753fe339755fda5bd8ca0c0aa3b658051\", \"mojo_unittests\": \"db31bb98f1d285844c675585d456f89e85da1ce4\", \"nacl_loader_unittests\": \"8f7324e4b71406738f1160913a9e84941f1f2867\", \"native_theme_unittests\": \"592a7736a8bd0345c62a28545506ddaac1ae1aa0\", \"net_unittests\": \"e090bf6e981640cd7c47d3d4bf75fd4df0035a50\", \"notification_helper_unittests\": \"08f042b7eea922f9e8e23a4960967134fff22106\", \"pdf_unittests\": \"a10665c263614ef5603c41407f740ee96c2eef3c\", \"perfetto_unittests\": \"d232aaa00814b97103f49b55419728a7ea8b169f\", \"ppapi_unittests\": \"09a212eecb5191652dfb7c5590d12196d6f07016\", \"printing_unittests\": \"b936e880c97b27a3e83e0097d8afaa4da0d09a3d\", \"remoting_unittests\": \"56f03f09f645d1198f1a5bdc1a377bdd497436c1\", \"sbox_integration_tests\": \"918c44c0dba0a7476ac0403e32c1ab20a0c3b4fa\", \"sbox_unittests\": \"3e90ce37a98cfc8214fa7bf36f691328e5fe1340\", \"sbox_validation_tests\": \"8e927bdb66d094882b16bd9dd2885175f1d53b0e\", \"service_manager_unittests\": \"a9e7eddc801fe9c0b2981b51bc9ed45ddf879bad\", \"services_unittests\": \"c351d0efe25a0f11def2c6864f2bcf2cfbe050ed\", \"setup_unittests\": \"1f875fcbc3919b3df856cbc0c8f76a65302f4d20\", \"shell_dialogs_unittests\": \"0cd5438f7263f59f1b8d28368a245047d34a0bac\", \"skia_unittests\": \"9db21c2730a41032242ca7ac5468ab8993e5862e\", \"snapshot_unittests\": \"58f1bb62d5123b2b05854836ebb93af6fabceac0\", \"sql_unittests\": \"47c132da9e8b679a7f214ffce49b52ec01323bda\", \"storage_unittests\": \"d1dd4f1c76880825ac916fa1dbd3bbfaed49b55b\", \"sync_integration_tests\": \"d89f717eeaba13bfad6963baa2aac44631c0a158\", \"traffic_annotation_auditor_unittests\": \"2f4a7eed60d8f9bc58273b19706aca6df832f51d\", \"ui_base_unittests\": \"3e1b6a19e61009e1f1ef417e147db9e85f521a11\", \"ui_touch_selection_unittests\": \"cb2346c20792e07ff6a229dec6e60ef618d7dcb3\", \"unit_tests\": \"01c47f08b4f75b2b859106104a6a6935db981e39\", \"updater_tests\": \"a5c6280bf0b66d94fc32b38f8f1be68e16e53cd6\", \"url_unittests\": \"2cd8594fcc2595a70a3651cad0e65e288c3ad289\", \"views_unittests\": \"4c874e2cdfda194c1c2220024dfd3af2692c75d9\", \"viz_unittests\": \"f0ceaf7c99cfecf9cf1224273203f149cc8c6778\", \"vr_common_unittests\": \"f2a9ca831663924e6ab3aedc1b6aa81d3a1e7437\", \"vr_pixeltests\": \"d5d65b003d3a2f084475741c24a5b11f23a4b639\", \"weblayer_browsertests\": \"3bdd5fad307727099f194e048b1b88869cebeaeb\", \"weblayer_unittests\": \"6aaefc17190be112952a12a8761f2074f63fdd6c\", \"wm_unittests\": \"99789ccca91223833f0dbe88a82b67288c2ea247\", \"wtf_unittests\": \"05515fb8a5f2b5cae3fb1fa24b7de9e4330969e2\", \"zlib_unittests\": \"a210b2dc73ef9bd9735550fd6e75ca91eed09365\", \"zucchini_unittests\": \"b306dc77361d37da49650fb7266fa492967c7b40\"}}, \"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-3-nb2x\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5661-c6f6250\"], \"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": "1610625936217188",
"status": "COMPLETED",
"status_changed_ts": "1610629378185379",
"tags": [
"build_address:luci.chromium.ci/win-asan/16815",
"builder:win-asan",
"buildset:commit/git/99127a0c0a7320fea49f63627931bdc09c6eb3f7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/99127a0c0a7320fea49f63627931bdc09c6eb3f7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023560078945178144",
"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/8858130016922596720/+/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:51199aecba661410",
"user_agent:luci-scheduler"
],
"updated_ts": "1610629378195988",
"url": "https://ci.chromium.org/b/8858130016922596720",
"utcnow_ts": "1610629378694797"
}
}
[13:02:58.704] Invocation finished in 57m26.335861015s with status SUCCEEDED