[04:46:32.171] New invocation is queued and will start shortly
[04:46:33.262] Starting the invocation (attempt 1)
[04:46:33.300] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:46:33.300] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:46:33.300] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028661128784108736",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"741bfd6a85c834760638ef8b467c26e89e6433f2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028661128784108736",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/741bfd6a85c834760638ef8b467c26e89e6433f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/741bfd6a85c834760638ef8b467c26e89e6433f2",
"gitiles_ref:refs/heads/master"
]
}
[04:46:34.332] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605761193391390",
"id": "8863231066836720624",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"741bfd6a85c834760638ef8b467c26e89e6433f2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605761194017974",
"tags": [
"build_address:luci.chromium.ci/win-asan/15716",
"builder:win-asan",
"buildset:commit/git/741bfd6a85c834760638ef8b467c26e89e6433f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/741bfd6a85c834760638ef8b467c26e89e6433f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028661128784108736",
"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/8863231066836720624/+/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": "1605761194018157",
"url": "https://ci.chromium.org/b/8863231066836720624",
"utcnow_ts": "1605761194319771"
}
}
[04:46:34.332] Task URL: https://ci.chromium.org/b/8863231066836720624
[04:46:34.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:2:0) after 1m0s
[04:46:38.036] Received PubSub notification, asking Buildbucket for the build status
[04:46:38.112] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:34.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:2:0)
[04:47:34.388] Timer tick, asking Buildbucket for the build status
[04:47:34.484] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:34.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:4:0) after 1m0s
[04:48:34.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:4:0)
[04:48:34.534] Timer tick, asking Buildbucket for the build status
[04:48:34.663] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:34.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:5:0) after 1m0s
[04:49:34.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:5:0)
[04:49:34.791] Timer tick, asking Buildbucket for the build status
[04:49:34.911] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:34.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:6:0) after 1m0s
[04:50:34.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:6:0)
[04:50:34.962] Timer tick, asking Buildbucket for the build status
[04:50:35.242] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:35.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:7:0) after 1m0s
[04:51:35.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:7:0)
[04:51:35.362] Timer tick, asking Buildbucket for the build status
[04:51:36.192] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:36.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:8:0) after 1m0s
[04:52:36.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:8:0)
[04:52:36.226] Timer tick, asking Buildbucket for the build status
[04:52:36.341] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:36.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:9:0) after 1m0s
[04:53:36.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:9:0)
[04:53:36.445] Timer tick, asking Buildbucket for the build status
[04:53:36.531] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:36.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:10:0) after 1m0s
[04:54:36.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:10:0)
[04:54:36.533] Timer tick, asking Buildbucket for the build status
[04:54:37.024] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:37.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:11:0) after 1m0s
[04:55:37.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:11:0)
[04:55:37.073] Timer tick, asking Buildbucket for the build status
[04:55:37.648] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:37.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:12:0) after 1m0s
[04:56:37.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:12:0)
[04:56:37.696] Timer tick, asking Buildbucket for the build status
[04:56:38.282] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:38.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:13:0) after 1m0s
[04:57:38.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:13:0)
[04:57:38.305] Timer tick, asking Buildbucket for the build status
[04:57:38.520] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:38.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:14:0) after 1m0s
[04:58:38.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:14:0)
[04:58:38.598] Timer tick, asking Buildbucket for the build status
[04:58:38.804] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:38.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:15:0) after 1m0s
[04:59:38.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:15:0)
[04:59:38.952] Timer tick, asking Buildbucket for the build status
[04:59:39.071] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:39.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:16:0) after 1m0s
[05:00:39.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:16:0)
[05:00:39.214] Timer tick, asking Buildbucket for the build status
[05:00:39.492] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:39.492] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:17:0) after 1m0s
[05:01:39.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:17:0)
[05:01:39.515] Timer tick, asking Buildbucket for the build status
[05:01:39.621] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:39.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:18:0) after 1m0s
[05:02:39.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:18:0)
[05:02:39.646] Timer tick, asking Buildbucket for the build status
[05:02:40.602] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:40.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:19:0) after 1m0s
[05:03:40.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:19:0)
[05:03:40.628] Timer tick, asking Buildbucket for the build status
[05:03:40.713] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:40.713] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:20:0) after 1m0s
[05:04:40.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:20:0)
[05:04:40.817] Timer tick, asking Buildbucket for the build status
[05:04:40.962] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:40.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:21:0) after 1m0s
[05:05:40.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:21:0)
[05:05:40.974] Timer tick, asking Buildbucket for the build status
[05:05:41.101] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:41.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:22:0) after 1m0s
[05:06:41.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:22:0)
[05:06:41.153] Timer tick, asking Buildbucket for the build status
[05:06:41.296] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:41.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:23:0) after 1m0s
[05:07:41.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:23:0)
[05:07:41.336] Timer tick, asking Buildbucket for the build status
[05:07:41.423] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:41.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:24:0) after 1m0s
[05:08:41.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:24:0)
[05:08:41.527] Timer tick, asking Buildbucket for the build status
[05:08:41.873] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:41.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:25:0) after 1m0s
[05:09:41.928] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:25:0)
[05:09:41.928] Timer tick, asking Buildbucket for the build status
[05:09:42.049] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:42.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:26:0) after 1m0s
[05:10:42.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:26:0)
[05:10:42.138] Timer tick, asking Buildbucket for the build status
[05:10:42.246] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:42.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:27:0) after 1m0s
[05:11:42.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:27:0)
[05:11:42.392] Timer tick, asking Buildbucket for the build status
[05:11:42.832] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:42.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:28:0) after 1m0s
[05:12:43.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:28:0)
[05:12:43.074] Timer tick, asking Buildbucket for the build status
[05:12:43.259] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:43.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:29:0) after 1m0s
[05:13:43.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:29:0)
[05:13:43.351] Timer tick, asking Buildbucket for the build status
[05:13:43.464] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:43.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:30:0) after 1m0s
[05:14:43.605] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:30:0)
[05:14:43.605] Timer tick, asking Buildbucket for the build status
[05:14:43.716] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:43.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:31:0) after 1m0s
[05:15:43.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:31:0)
[05:15:43.936] Timer tick, asking Buildbucket for the build status
[05:15:44.054] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:44.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:32:0) after 1m0s
[05:16:44.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:32:0)
[05:16:44.140] Timer tick, asking Buildbucket for the build status
[05:16:44.269] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:44.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:33:0) after 1m0s
[05:17:44.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:33:0)
[05:17:44.424] Timer tick, asking Buildbucket for the build status
[05:17:44.573] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:44.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:34:0) after 1m0s
[05:18:44.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:34:0)
[05:18:44.696] Timer tick, asking Buildbucket for the build status
[05:18:44.779] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:44.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:35:0) after 1m0s
[05:19:44.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:35:0)
[05:19:44.938] Timer tick, asking Buildbucket for the build status
[05:19:45.068] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:45.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:36:0) after 1m0s
[05:20:45.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:36:0)
[05:20:45.102] Timer tick, asking Buildbucket for the build status
[05:20:45.660] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:45.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:37:0) after 1m0s
[05:21:45.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:37:0)
[05:21:45.940] Timer tick, asking Buildbucket for the build status
[05:21:46.643] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:46.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:38:0) after 1m0s
[05:22:46.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:38:0)
[05:22:46.887] Timer tick, asking Buildbucket for the build status
[05:22:46.970] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:46.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:39:0) after 1m0s
[05:23:47.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:39:0)
[05:23:47.226] Timer tick, asking Buildbucket for the build status
[05:23:47.407] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:47.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:40:0) after 1m0s
[05:24:47.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:40:0)
[05:24:47.448] Timer tick, asking Buildbucket for the build status
[05:24:47.521] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:47.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:41:0) after 1m0s
[05:25:47.609] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:41:0)
[05:25:47.609] Timer tick, asking Buildbucket for the build status
[05:25:47.762] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:47.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:42:0) after 1m0s
[05:26:47.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:42:0)
[05:26:47.763] Timer tick, asking Buildbucket for the build status
[05:26:47.905] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:47.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:43:0) after 1m0s
[05:27:47.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:43:0)
[05:27:47.925] Timer tick, asking Buildbucket for the build status
[05:27:48.074] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:48.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:44:0) after 1m0s
[05:28:48.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:44:0)
[05:28:48.113] Timer tick, asking Buildbucket for the build status
[05:28:48.401] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:48.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:45:0) after 1m0s
[05:30:02.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:45:0)
[05:30:02.479] Timer tick, asking Buildbucket for the build status
[05:30:02.803] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:02.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:46:0) after 1m0s
[05:31:02.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:46:0)
[05:31:02.887] Timer tick, asking Buildbucket for the build status
[05:31:03.374] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:03.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:47:0) after 1m0s
[05:32:03.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:47:0)
[05:32:03.447] Timer tick, asking Buildbucket for the build status
[05:32:03.599] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:03.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:48:0) after 1m0s
[05:33:03.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:48:0)
[05:33:03.627] Timer tick, asking Buildbucket for the build status
[05:33:03.823] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:03.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:49:0) after 1m0s
[05:34:03.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:49:0)
[05:34:03.957] Timer tick, asking Buildbucket for the build status
[05:34:04.317] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:04.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:50:0) after 1m0s
[05:35:04.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:50:0)
[05:35:04.356] Timer tick, asking Buildbucket for the build status
[05:35:05.142] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:05.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:51:0) after 1m0s
[05:36:05.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:51:0)
[05:36:05.415] Timer tick, asking Buildbucket for the build status
[05:36:05.803] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:05.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:52:0) after 1m0s
[05:37:06.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:52:0)
[05:37:06.037] Timer tick, asking Buildbucket for the build status
[05:37:06.733] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:06.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:53:0) after 1m0s
[05:38:06.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:53:0)
[05:38:06.743] Timer tick, asking Buildbucket for the build status
[05:38:06.824] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:06.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:54:0) after 1m0s
[05:39:06.845] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:54:0)
[05:39:06.845] Timer tick, asking Buildbucket for the build status
[05:39:06.913] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:06.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:55:0) after 1m0s
[05:40:07.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:55:0)
[05:40:07.099] Timer tick, asking Buildbucket for the build status
[05:40:07.187] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:07.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:56:0) after 1m0s
[05:41:07.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:56:0)
[05:41:07.318] Timer tick, asking Buildbucket for the build status
[05:41:07.696] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:07.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:57:0) after 1m0s
[05:42:07.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:57:0)
[05:42:07.757] Timer tick, asking Buildbucket for the build status
[05:42:07.925] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:07.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:58:0) after 1m0s
[05:43:07.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:58:0)
[05:43:07.927] Timer tick, asking Buildbucket for the build status
[05:43:08.084] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:08.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:59:0) after 1m0s
[05:44:08.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:59:0)
[05:44:08.159] Timer tick, asking Buildbucket for the build status
[05:44:08.565] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:08.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:60:0) after 1m0s
[05:45:08.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:60:0)
[05:45:08.624] Timer tick, asking Buildbucket for the build status
[05:45:09.508] Build 8863231066836720624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:09.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028661128784108736:61:0) after 1m0s
[05:45:52.751] Received PubSub notification, asking Buildbucket for the build status
[05:45:52.862] Build 8863231066836720624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:45:52.862] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605764751385263",
"created_by": "project:chromium",
"created_ts": "1605761193391390",
"id": "8863231066836720624",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"741bfd6a85c834760638ef8b467c26e89e6433f2\"}}",
"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\": \"741bfd6a85c834760638ef8b467c26e89e6433f2\", \"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-0-m5ra\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15716, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"8a58e38bfd26672fe5d143a6f96a041f626bde33\", \"got_dawn_revision\": \"ce207be41fd6d9f1334ca6f251812511dc63faca\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"741bfd6a85c834760638ef8b467c26e89e6433f2\", \"got_revision_cp\": \"refs/heads/master@{#829093}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"060020166679a265a2bc8250f4d78a7a8c29de89\", \"got_v8_revision\": \"458ab69acb38af95d9d4eb5a7e0f692f3cfe81d0\", \"got_v8_revision_cp\": \"refs/heads/8.9.11@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"741bfd6a85c834760638ef8b467c26e89e6433f2\", \"swarm_hashes_refs/heads/master(at){#829093}_without_patch\": {\"absl_hardening_tests\": \"990d737231e835dfe9827e0707e137bdbb0a3e28\", \"accessibility_unittests\": \"3f83ee76a3dd5caa541213c140cce7ddb1c7c0ff\", \"angle_unittests\": \"f10d98f814c829dacb63d38589136c3ec8d6395f\", \"app_shell_unittests\": \"94238821ef1fa28bcdea3da2a1e35503d403928c\", \"aura_unittests\": \"fbff9f8e8297147bad62bd88709202d9d377ab5c\", \"base_unittests\": \"6ca0c4f49251dc68134729849d65fcfbcc3e736f\", \"base_util_unittests\": \"d3c07f270a830a1465079bba0241af290e49aa6e\", \"blink_common_unittests\": \"102587fa39a2e4d50aaee2f619f96d7515cf8040\", \"blink_fuzzer_unittests\": \"b434e80e02a3bd15d3bf4e23459a002e81626c43\", \"blink_heap_unittests\": \"24cf7249a7fde7e1217a3d32e184c3cb59b99019\", \"blink_platform_unittests\": \"fa7853bd77992b0f29bf55291373f78bd1396484\", \"blink_unittests\": \"5acee9050e552d2e5a9811d42fb657db2d26cb65\", \"boringssl_crypto_tests\": \"aeca3c1bc5b282b79535ab9348be19472fac00cf\", \"boringssl_ssl_tests\": \"ccc68487c7ac10d164eed2ef2ed828755eb794c2\", \"browser_switcher_bho_unittests\": \"49bbefffec11ad089232b1e85262e352af5d09e4\", \"browser_tests\": \"01afd57be8e249c9a1aa567ffa44d756393de32b\", \"capture_unittests\": \"4c39af932e232c39ce3722271e0658be86ac834b\", \"cast_unittests\": \"e56bc46ef51630e6637dc49c1f6036efbc3b94de\", \"cc_unittests\": \"ae95955f0d27cea76fa97e38cc8f2062e36e0303\", \"chrome_app_unittests\": \"35883df91c3c2c5c9d5429fff4dd69bdb2ce0b9f\", \"chrome_cleaner_unittests\": \"f188c520976c94a47400c8758418bdd80cc1275f\", \"chrome_elf_unittests\": \"5f1077ebcb0bab2c6b9fcbf2a86d9b5942469b66\", \"chromedriver_unittests\": \"224436b69173aab4667494704c51b6b5422571b1\", \"components_browsertests\": \"198d02e79e71a0e5b992b4bd123aa3967e467b79\", \"components_unittests\": \"2537ce51ec2e8c93fc851403933c69237b2ad6c9\", \"compositor_unittests\": \"f372e21097c4de98a64cfc249ca243cdc529d423\", \"content_browsertests\": \"b73bdf3be77d4827a17a9349b22bdf226dea99f7\", \"content_unittests\": \"f03e116ac535ed8c75fa8e8e5c6d2b552d851154\", \"courgette_unittests\": \"e377e8f1da2bcdce5356534daab4cd99da00f0fc\", \"crashpad_tests\": \"296afb8350a8b16470ed1ab3fcd8a9d612f4e796\", \"cronet_tests\": \"8c34a62acdfcc915f1763e2db68a2d569fd1f994\", \"cronet_unittests\": \"d584c374b93b8a36ed5021df770506f0cf9bb829\", \"crypto_unittests\": \"74bbadfcc7484afeda584c458f146270f44b26c5\", \"delayloads_unittests\": \"3d5afe8154d3afbea0a2cfa9d42b479b85a0b00a\", \"device_unittests\": \"11b2ba9cb4d35df4909a3c4792f6f98eee3e856b\", \"display_unittests\": \"956d4930264316609a8c0424d2b2aa616abab712\", \"elevation_service_unittests\": \"5021c5369bf9b1e45e512862d2603f50add17ada\", \"events_unittests\": \"6ba922e1cce255ef97d4ab1511aeebe066428d06\", \"extensions_browsertests\": \"7036e1fc19955570d4a40d68845d9cb82b27fb94\", \"extensions_unittests\": \"9bab99ecfad9510917a0bb9251e1b7d3fdc0a489\", \"filesystem_service_unittests\": \"b2459fd14f75249f564fd2dc493207b986d9b15f\", \"gcm_unit_tests\": \"1629ada2504aae06f1779249067d399b2899cc25\", \"gcp_unittests\": \"54e29d2884daab7637fccebb617f797c4181f58c\", \"gfx_unittests\": \"2c64294a03c7e101d9f34380eb7a5f809f22c47f\", \"gin_unittests\": \"ed0e6067a40c421a84ac007a4bc17f58e6b59c78\", \"google_apis_unittests\": \"eac68b322ee0eeea2faad199a1eeec0b3ebe2fd8\", \"gpu_unittests\": \"3f9c0b481fe16353ee251950b5567fc1b8087764\", \"gwp_asan_unittests\": \"21c693dac7952cca6c5754444b197f514c98dbe9\", \"headless_browsertests\": \"9f27d4a9d4f5a8af74fb6547fd6c5786e6c97ed1\", \"headless_unittests\": \"3533b4a3081133cb411b858bdf414813a4317512\", \"install_static_unittests\": \"76f66f8d0932489d7184370f6bf9277b93fdca36\", \"installer_util_unittests\": \"c4cb9978270282344074d8e1c143c6505caa01bd\", \"interactive_ui_tests\": \"a0ae4f585a1f5cc1a22a5fcc10c0b9baa0ff57bd\", \"ipc_tests\": \"a8abdfac085d826416e7660d9b21ecb50e3cc467\", \"jingle_unittests\": \"967a7b93b0fac835724b667c8d0a096ef05d617e\", \"latency_unittests\": \"e6d50003039315c0bd0bb6cca980af1100892856\", \"libjingle_xmpp_unittests\": \"896ddfc6230fe0c2ff0f06f472350441f2557d39\", \"liburlpattern_unittests\": \"e0ee7d7944b9ee504440e4f9e627b93e32e9a9fd\", \"media_blink_unittests\": \"1da8322660481606efbc3cb1dd26f6396baac3df\", \"media_unittests\": \"108bab0c1af60f37e2afbc263f064c8aff31cacd\", \"message_center_unittests\": \"63f0d0cf9bfcb19a458b8a4dd65c71b1220957a9\", \"midi_unittests\": \"36c8da313091aacbe254c573aae4c08e1a24e684\", \"mojo_core_unittests\": \"686e6d4f003c9899ab940db271f66aeab9f9628c\", \"mojo_unittests\": \"b7bab01597e742a3aa5ca3b0c49715ad99128c3b\", \"nacl_loader_unittests\": \"ee0cb067841f7b620a21ec783795930440e1c1b3\", \"native_theme_unittests\": \"c88f85384cb6cfc56a72536f43437a43da683444\", \"net_unittests\": \"8f940cb4816dd047f88c053bae68467d33b49bb5\", \"notification_helper_unittests\": \"1e3741953e4d02ce2058cc57558ace7ca113601b\", \"pdf_unittests\": \"6430dc27c4867e752a76429aed3ffd2ee1d53f6d\", \"perfetto_unittests\": \"1ce264301dd83db05ef84614d0f87e67bcd9022d\", \"ppapi_unittests\": \"d323c27c4b1fafd875f1dcacbeac6247fa6948d2\", \"printing_unittests\": \"48954bf805e2b029150ea9dd8e37dd048a0acf13\", \"remoting_unittests\": \"f5ef0e8973c381f0b1ac97ddb39f5a8e8e918d0c\", \"sbox_integration_tests\": \"c852b4fb2f62199851e6a8df78fcbcdf0bd5f99c\", \"sbox_unittests\": \"dbcaa926e2dec8bc79372433699785a0e91f229c\", \"sbox_validation_tests\": \"62f9fc90634eaf0f706ee4dad2a061eecb048256\", \"service_manager_unittests\": \"4e80c0604b1b059d55e9da730a73bcedcaf39a0b\", \"services_unittests\": \"24d024c6b2e05cebee04ebf351541a5fe4ebcefd\", \"setup_unittests\": \"ab16a9055acd9a00ea9f278949509058a736a018\", \"shell_dialogs_unittests\": \"7aa0b0f9a22b1c4a67ad446776e278c39468193f\", \"skia_unittests\": \"f43074b6a7bcc906b46e162a99c0a344469acd88\", \"snapshot_unittests\": \"020266a91a562f73a3d6c5f1749bab424e286684\", \"sql_unittests\": \"2d39ac066e18d31a63e59d121132b0e0194a6367\", \"storage_unittests\": \"dcbaceec30e98900378fb4ee8ba86b3322855c95\", \"sync_integration_tests\": \"0a105a409f4be5b143a038d0aac17fbc363956b4\", \"traffic_annotation_auditor_unittests\": \"5172ec8caa64b18a32dadeefbed6348bf20290e5\", \"ui_base_unittests\": \"8bc0055a0e4d961df59467398f8670472e4d968a\", \"ui_touch_selection_unittests\": \"7efb4a6b0fb1ddbb7bab122e085ac8f0f49d17a3\", \"unit_tests\": \"0acc73bcc63298d8a19f47060b2ee4f6c796f1e9\", \"updater_tests\": \"2ac053fa2cc8ae2d721e44c29b4f7ad43dbcf3d9\", \"url_unittests\": \"ca716c81cc84bee6a7d2803b434eb44b75dcb44e\", \"views_unittests\": \"350d7d844adac564d731c9e213726da20e2e6aa8\", \"viz_unittests\": \"39120db44746d11dba91ec70355d294507cfe91f\", \"vr_common_unittests\": \"a84e9d33a7689a47cd24663a8e0d35d96a9b83ba\", \"vr_pixeltests\": \"933151ee28cbde2a1d750a5e272c7cba3ed86845\", \"weblayer_browsertests\": \"3e393d5d2d0a2476444ea753af84a21874663d92\", \"weblayer_unittests\": \"3acaa3851cf5beebc5a2d3f80ba9751390de5ac9\", \"wm_unittests\": \"9d43dc6c08cb8d9db539068b397d02aa42844c1e\", \"wtf_unittests\": \"de953295b56687712649485f083a13de0357f094\", \"zlib_unittests\": \"aea3552d16ba0680d919671c4bb9d33cf38f264c\", \"zucchini_unittests\": \"38504db4100acea5a66b3efe02fbfbb512ec3770\"}}, \"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-0-m5ra\"], \"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\": [\"5635-1756fdb\"], \"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": "1605761197296511",
"status": "COMPLETED",
"status_changed_ts": "1605764751819332",
"tags": [
"build_address:luci.chromium.ci/win-asan/15716",
"builder:win-asan",
"buildset:commit/git/741bfd6a85c834760638ef8b467c26e89e6433f2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/741bfd6a85c834760638ef8b467c26e89e6433f2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028661128784108736",
"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/8863231066836720624/+/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:4ff7a4e275155b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605764751835091",
"url": "https://ci.chromium.org/b/8863231066836720624",
"utcnow_ts": "1605764752846703"
}
}
[05:45:52.862] Invocation finished in 59m20.710756307s with status SUCCEEDED