[00:12:41.662] New invocation is queued and will start shortly
[00:12:42.833] Starting the invocation (attempt 1)
[00:12:42.876] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:12:42.878] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:12:42.878] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022970748532869440",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b878cc85da6730d506e5c671b667be2b19466fd8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022970748532869440",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b878cc85da6730d506e5c671b667be2b19466fd8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b878cc85da6730d506e5c671b667be2b19466fd8",
"gitiles_ref:refs/heads/master"
]
}
[00:12:44.217] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611187963017169",
"id": "8857540686449784880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b878cc85da6730d506e5c671b667be2b19466fd8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611187963928815",
"tags": [
"build_address:luci.chromium.ci/win-asan/16920",
"builder:win-asan",
"buildset:commit/git/b878cc85da6730d506e5c671b667be2b19466fd8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b878cc85da6730d506e5c671b667be2b19466fd8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022970748532869440",
"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/8857540686449784880/+/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": "1611187963928946",
"url": "https://ci.chromium.org/b/8857540686449784880",
"utcnow_ts": "1611187964202042"
}
}
[00:12:44.217] Task URL: https://ci.chromium.org/b/8857540686449784880
[00:12:44.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:2:0) after 1m0s
[00:12:47.184] Received PubSub notification, asking Buildbucket for the build status
[00:12:47.283] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:2:0)
[00:13:44.410] Timer tick, asking Buildbucket for the build status
[00:13:44.528] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:13:44.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:4:0) after 1m0s
[00:14:44.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:4:0)
[00:14:44.733] Timer tick, asking Buildbucket for the build status
[00:14:44.854] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:14:44.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:5:0) after 1m0s
[00:15:44.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:5:0)
[00:15:44.854] Timer tick, asking Buildbucket for the build status
[00:15:44.982] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:15:44.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:6:0) after 1m0s
[00:16:45.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:6:0)
[00:16:45.048] Timer tick, asking Buildbucket for the build status
[00:16:45.199] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:16:45.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:7:0) after 1m0s
[00:17:45.364] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:7:0)
[00:17:45.364] Timer tick, asking Buildbucket for the build status
[00:17:45.709] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:17:45.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:8:0) after 1m0s
[00:18:45.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:8:0)
[00:18:45.825] Timer tick, asking Buildbucket for the build status
[00:18:45.927] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:18:45.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:9:0) after 1m0s
[00:19:45.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:9:0)
[00:19:45.975] Timer tick, asking Buildbucket for the build status
[00:19:46.656] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:19:46.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:10:0) after 1m0s
[00:20:46.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:10:0)
[00:20:46.903] Timer tick, asking Buildbucket for the build status
[00:20:46.968] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:20:46.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:11:0) after 1m0s
[00:21:47.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:11:0)
[00:21:47.094] Timer tick, asking Buildbucket for the build status
[00:21:47.157] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:21:47.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:12:0) after 1m0s
[00:22:47.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:12:0)
[00:22:47.351] Timer tick, asking Buildbucket for the build status
[00:22:47.532] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:22:47.532] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:13:0) after 1m0s
[00:23:47.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:13:0)
[00:23:47.797] Timer tick, asking Buildbucket for the build status
[00:23:47.858] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:23:47.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:14:0) after 1m0s
[00:24:47.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:14:0)
[00:24:47.967] Timer tick, asking Buildbucket for the build status
[00:24:48.032] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:24:48.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:15:0) after 1m0s
[00:25:48.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:15:0)
[00:25:48.139] Timer tick, asking Buildbucket for the build status
[00:25:48.215] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:25:48.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:16:0) after 1m0s
[00:26:48.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:16:0)
[00:26:48.466] Timer tick, asking Buildbucket for the build status
[00:26:48.655] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:26:48.655] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:17:0) after 1m0s
[00:27:48.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:17:0)
[00:27:48.765] Timer tick, asking Buildbucket for the build status
[00:27:48.887] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:27:48.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:18:0) after 1m0s
[00:28:49.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:18:0)
[00:28:49.237] Timer tick, asking Buildbucket for the build status
[00:28:49.303] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:28:49.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:19:0) after 1m0s
[00:29:49.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:19:0)
[00:29:49.448] Timer tick, asking Buildbucket for the build status
[00:29:49.522] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:29:49.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:20:0) after 1m0s
[00:30:49.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:20:0)
[00:30:49.675] Timer tick, asking Buildbucket for the build status
[00:30:49.735] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:30:49.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:21:0) after 1m0s
[00:31:49.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:21:0)
[00:31:49.742] Timer tick, asking Buildbucket for the build status
[00:31:49.915] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:49.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:22:0) after 1m0s
[00:32:50.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:22:0)
[00:32:50.004] Timer tick, asking Buildbucket for the build status
[00:32:50.135] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:50.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:23:0) after 1m0s
[00:33:50.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:23:0)
[00:33:50.356] Timer tick, asking Buildbucket for the build status
[00:33:50.429] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:50.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:24:0) after 1m0s
[00:34:50.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:24:0)
[00:34:50.637] Timer tick, asking Buildbucket for the build status
[00:34:50.815] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:50.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:25:0) after 1m0s
[00:35:51.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:25:0)
[00:35:51.175] Timer tick, asking Buildbucket for the build status
[00:35:51.289] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:51.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:26:0) after 1m0s
[00:36:51.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:26:0)
[00:36:51.357] Timer tick, asking Buildbucket for the build status
[00:36:51.606] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:51.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:27:0) after 1m0s
[00:37:51.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:27:0)
[00:37:51.691] Timer tick, asking Buildbucket for the build status
[00:37:52.124] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:52.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:28:0) after 1m0s
[00:38:52.159] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:28:0)
[00:38:52.159] Timer tick, asking Buildbucket for the build status
[00:38:52.292] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:52.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:29:0) after 1m0s
[00:39:52.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:29:0)
[00:39:52.328] Timer tick, asking Buildbucket for the build status
[00:39:52.422] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:52.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:30:0) after 1m0s
[00:40:52.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:30:0)
[00:40:52.472] Timer tick, asking Buildbucket for the build status
[00:40:52.622] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:52.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:31:0) after 1m0s
[00:41:52.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:31:0)
[00:41:52.674] Timer tick, asking Buildbucket for the build status
[00:41:52.783] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:52.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:32:0) after 1m0s
[00:42:52.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:32:0)
[00:42:52.879] Timer tick, asking Buildbucket for the build status
[00:42:53.010] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:53.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:33:0) after 1m0s
[00:43:53.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:33:0)
[00:43:53.027] Timer tick, asking Buildbucket for the build status
[00:43:53.145] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:53.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:34:0) after 1m0s
[00:44:53.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:34:0)
[00:44:53.226] Timer tick, asking Buildbucket for the build status
[00:44:53.341] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:53.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:35:0) after 1m0s
[00:45:53.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:35:0)
[00:45:53.388] Timer tick, asking Buildbucket for the build status
[00:45:53.516] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:53.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:36:0) after 1m0s
[00:46:53.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:36:0)
[00:46:53.686] Timer tick, asking Buildbucket for the build status
[00:46:53.800] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:53.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:37:0) after 1m0s
[00:47:53.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:37:0)
[00:47:53.852] Timer tick, asking Buildbucket for the build status
[00:47:53.969] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:53.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:38:0) after 1m0s
[00:48:54.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:38:0)
[00:48:54.045] Timer tick, asking Buildbucket for the build status
[00:48:54.197] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:54.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:39:0) after 1m0s
[00:49:54.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:39:0)
[00:49:54.218] Timer tick, asking Buildbucket for the build status
[00:49:54.312] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:54.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:40:0) after 1m0s
[00:50:54.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:40:0)
[00:50:54.385] Timer tick, asking Buildbucket for the build status
[00:50:54.898] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:54.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:41:0) after 1m0s
[00:51:55.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:41:0)
[00:51:55.037] Timer tick, asking Buildbucket for the build status
[00:51:55.113] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:55.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:42:0) after 1m0s
[00:52:55.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:42:0)
[00:52:55.252] Timer tick, asking Buildbucket for the build status
[00:52:55.368] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:55.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:43:0) after 1m0s
[00:53:55.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:43:0)
[00:53:55.413] Timer tick, asking Buildbucket for the build status
[00:53:55.674] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:55.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:44:0) after 1m0s
[00:54:55.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:44:0)
[00:54:55.722] Timer tick, asking Buildbucket for the build status
[00:54:55.836] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:55.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:45:0) after 1m0s
[00:55:55.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:45:0)
[00:55:55.957] Timer tick, asking Buildbucket for the build status
[00:55:56.353] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:56.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:46:0) after 1m0s
[00:56:56.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:46:0)
[00:56:56.343] Timer tick, asking Buildbucket for the build status
[00:56:56.465] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:56.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:47:0) after 1m0s
[00:57:56.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:47:0)
[00:57:56.508] Timer tick, asking Buildbucket for the build status
[00:57:57.138] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:57.138] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:48:0) after 1m0s
[00:58:57.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:48:0)
[00:58:57.180] Timer tick, asking Buildbucket for the build status
[00:58:57.274] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:57.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:49:0) after 1m0s
[00:59:57.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:49:0)
[00:59:57.340] Timer tick, asking Buildbucket for the build status
[00:59:57.582] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:57.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:50:0) after 1m0s
[01:00:57.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:50:0)
[01:00:57.692] Timer tick, asking Buildbucket for the build status
[01:00:57.803] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:57.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:51:0) after 1m0s
[01:01:57.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:51:0)
[01:01:57.974] Timer tick, asking Buildbucket for the build status
[01:01:58.049] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:58.049] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:52:0) after 1m0s
[01:02:58.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:52:0)
[01:02:58.162] Timer tick, asking Buildbucket for the build status
[01:02:58.301] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:58.301] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:53:0) after 1m0s
[01:03:58.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:53:0)
[01:03:58.353] Timer tick, asking Buildbucket for the build status
[01:03:58.624] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:58.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:54:0) after 1m0s
[01:04:58.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:54:0)
[01:04:58.683] Timer tick, asking Buildbucket for the build status
[01:04:58.818] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:58.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:55:0) after 1m0s
[01:05:58.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:55:0)
[01:05:58.888] Timer tick, asking Buildbucket for the build status
[01:05:59.014] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:59.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:56:0) after 1m0s
[01:06:59.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:56:0)
[01:06:59.106] Timer tick, asking Buildbucket for the build status
[01:06:59.217] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:59.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:57:0) after 1m0s
[01:07:59.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:57:0)
[01:07:59.342] Timer tick, asking Buildbucket for the build status
[01:07:59.664] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:59.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:58:0) after 1m0s
[01:08:59.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:58:0)
[01:08:59.775] Timer tick, asking Buildbucket for the build status
[01:08:59.865] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:59.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:59:0) after 1m0s
[01:09:59.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:59:0)
[01:09:59.953] Timer tick, asking Buildbucket for the build status
[01:10:00.055] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:00.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:60:0) after 1m0s
[01:11:00.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:60:0)
[01:11:00.077] Timer tick, asking Buildbucket for the build status
[01:11:01.088] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:01.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:61:0) after 1m0s
[01:12:01.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:61:0)
[01:12:01.198] Timer tick, asking Buildbucket for the build status
[01:12:01.471] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:01.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:62:0) after 1m0s
[01:13:01.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:62:0)
[01:13:01.497] Timer tick, asking Buildbucket for the build status
[01:13:01.901] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:01.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:63:0) after 1m0s
[01:14:02.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:63:0)
[01:14:02.110] Timer tick, asking Buildbucket for the build status
[01:14:02.190] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:02.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:64:0) after 1m0s
[01:15:02.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:64:0)
[01:15:02.337] Timer tick, asking Buildbucket for the build status
[01:15:02.582] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:02.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:65:0) after 1m0s
[01:16:02.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:65:0)
[01:16:02.688] Timer tick, asking Buildbucket for the build status
[01:16:03.643] Build 8857540686449784880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:03.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022970748532869440:66:0) after 1m0s
[01:16:46.878] Received PubSub notification, asking Buildbucket for the build status
[01:16:47.500] Build 8857540686449784880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:16:47.500] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611191805174318",
"created_by": "project:chromium",
"created_ts": "1611187963017169",
"id": "8857540686449784880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b878cc85da6730d506e5c671b667be2b19466fd8\"}}",
"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\": \"b878cc85da6730d506e5c671b667be2b19466fd8\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16920, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"ea71c6b63417af438afaf26a886b03621d85f14f\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"b878cc85da6730d506e5c671b667be2b19466fd8\", \"got_revision_cp\": \"refs/heads/master@{#845417}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"0be1846477949c472a7a2bf42134a7bb08570ac4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33046}\", \"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\": \"b878cc85da6730d506e5c671b667be2b19466fd8\", \"swarm_hashes_refs/heads/master(at){#845417}_without_patch\": {\"absl_hardening_tests\": \"3f014dd4de63d7e9a7ca29581ec8f37e7812391b\", \"accessibility_unittests\": \"ce384c6223eeb114fbe54205b9b6f890f98bc3bc\", \"angle_unittests\": \"0aa149c8a1e3f3b1a0979d82523aebdbda6746e9\", \"app_shell_unittests\": \"50bda522c43b59520c4d4db24f421ed265ba70e7\", \"aura_unittests\": \"98100531df970e0f4d5a4fd1bbf41caae746c572\", \"base_unittests\": \"399dc1aa7441b22b74526936c93776f0fa70ebf9\", \"base_util_unittests\": \"5feac01c8bcee32e9be61b4d234f5c57ccfd5f14\", \"blink_common_unittests\": \"f79182852dfa0136cc1a599fc2e5169ed1f0ba36\", \"blink_fuzzer_unittests\": \"fa06dcfeae646b9162135a00774e451ff9620114\", \"blink_heap_unittests\": \"65a55a0c0f30a81a0fa176a1d0a5b61bff49e627\", \"blink_platform_unittests\": \"2ce35efe9ba5ced2ceac603e7e5afbeb4d496b6b\", \"blink_unittests\": \"258fd24894f560df4a3b6e5d01cceaf93569c1f1\", \"boringssl_crypto_tests\": \"5282675f6387eadd66b96136ef4a12cd746074b7\", \"boringssl_ssl_tests\": \"83d7251e3df241388c47fb434a026c33958d9cf4\", \"browser_switcher_bho_unittests\": \"4cb24c4ec8ecf478b004b6831b2284a28e95bc0b\", \"browser_tests\": \"6912e6ab3edc279e0ee5d3c58972f18e6bc034fa\", \"capture_unittests\": \"6690f077a2508721834dc38f8a5e1bb69b841dad\", \"cast_unittests\": \"6db9c49c2d2dfe2c8160d0795021af14a25dbed7\", \"cc_unittests\": \"c97244f3ae3cace1d0c89ce82846b0c4a18b6dc5\", \"chrome_app_unittests\": \"5473e4ca02898008990d472befe8dedd1e2760b7\", \"chrome_cleaner_unittests\": \"9dee7b21b3aee6da55c1a6ac21594befaeae537c\", \"chrome_elf_unittests\": \"231e60cbc8abb930029a1705e96214bc859d7e07\", \"chromedriver_unittests\": \"65d126b6323456169cb1245aaf515abfe35fc5ed\", \"components_browsertests\": \"f80e972bed3485e5fda231f7560f141d760df12a\", \"components_unittests\": \"f783e7f9b1ea24ea3f0d9702cbd98ed06a4b4a73\", \"compositor_unittests\": \"95cd316f4c0bb5fb0adc6e16e155e7b32e53139c\", \"content_browsertests\": \"0bf0c586567c2d99c5302ca10892734f7cf3672a\", \"content_unittests\": \"7e5be9d62b2b6cffc32a1dedfc94f275b28621ac\", \"courgette_unittests\": \"c5b00908647a4357e4222ca8171329e3f64f76b7\", \"crashpad_tests\": \"487bb6a15a562ca0e9a2b95ec465163812ec74b6\", \"cronet_tests\": \"279086f480b6a530c781d4286d19d5ed59019cd4\", \"cronet_unittests\": \"6b5b29ddbad55b9d5efd0dab4c61c9483c90084c\", \"crypto_unittests\": \"e46087da67e759acd46f104161063dd549077bd0\", \"delayloads_unittests\": \"0a53160fd02f7b32684eab53b81195ae494e88c2\", \"device_unittests\": \"94f5eed11dae245a725e627165749dee28e816d5\", \"display_unittests\": \"bc1b127c89f414f3a63e36bb495ece5d192c1366\", \"elevation_service_unittests\": \"6234cd721c07f9bdd820d99356f591d0f1e0c4b5\", \"events_unittests\": \"2d872d440f505bb1ac595a0aea418ff727c6f383\", \"extensions_browsertests\": \"a98d9ace5e7feb6dcbf02d615ca51cd6f20109a0\", \"extensions_unittests\": \"05d9925ed76dcc545bbc7d7e62d998b5a8aeacb0\", \"filesystem_service_unittests\": \"d6cbffe41e97ba59167acbde9316baeb3774a0c3\", \"gcm_unit_tests\": \"50c1b1c6815279f8b554dc87e1bce404aa6d85f1\", \"gcp_unittests\": \"98a13cb2af470cf160780c8177528c1a6db602bb\", \"gfx_unittests\": \"d47c7b49a26fa8bbaa579471a20a7b1128a437ef\", \"gin_unittests\": \"bb4ce78407c3cf004f2f294100a84f7685342cba\", \"google_apis_unittests\": \"71ed8104247806773e1cd4d0ace075bb13428979\", \"gpu_unittests\": \"74d411cb8f67d5f7c812e48fffebd227ca11fe11\", \"gwp_asan_unittests\": \"fad6e72c1489819e4a502ca54f5b0e1441b362b6\", \"headless_browsertests\": \"227bfc77b44189ce591698dbf937d4c577a36c2f\", \"headless_unittests\": \"3ffda8787f67369c6ea4d715e49b8350d5a7ad8b\", \"install_static_unittests\": \"4ead9c9be42be84a6464a752e092f6a9378bf739\", \"installer_util_unittests\": \"46c0dcb4121a7c940d17c339d8874717c40a9ce4\", \"interactive_ui_tests\": \"e0236b95745fc3eba1903cdf96c60a316691c796\", \"ipc_tests\": \"08f57c08735b6748327c1975db26ff5072c3f182\", \"jingle_unittests\": \"6728cadf8ac89c44fbfe6d09ae8b7b2f91ab599b\", \"latency_unittests\": \"0ac4a3b2a61bfa95bfe8d5d9d0a5a47263a5b634\", \"libjingle_xmpp_unittests\": \"5097ce313c5d1790e2408f0a67fc153873a7276f\", \"liburlpattern_unittests\": \"03158c6a4b87c58307eab8d953c9a22c715bdd5c\", \"media_blink_unittests\": \"3d39e4c19025bce500c5dc9eab86805df88bac92\", \"media_unittests\": \"73fcd18b6b4d31c52cb51ea7d4197022457e1232\", \"message_center_unittests\": \"13af920b83472f258d0d26f6f546335782dc8ba6\", \"midi_unittests\": \"fa184b076c6f8a2eee199d0145580ee466eb7c3d\", \"mojo_core_unittests\": \"935cf046560a69ed21f97cce7068e624a2c1b421\", \"mojo_unittests\": \"ce7784a9942e3c2596c4dd52f1877f06e805e37e\", \"nacl_loader_unittests\": \"d8bc74aadb11d986e06412841f87b4922e6870d7\", \"native_theme_unittests\": \"a2df5be4ccc36a409f664eb1345ae9b7f5c5d502\", \"net_unittests\": \"69c5466cbf65b976c384945d17de0ef52f8fb22e\", \"notification_helper_unittests\": \"778aee96ad82fa849cb93b2f35d21840c40afe64\", \"pdf_unittests\": \"6d52199c35a07788e53ed6b52bed31055735dfa7\", \"perfetto_unittests\": \"2454099c418a7747e4f8c8034b9dbc8906b8de27\", \"ppapi_unittests\": \"26e80b89bf4cc6aa1cd481d4e00e7d8e945295ba\", \"printing_unittests\": \"088667067e969f88ce577dfe150c64fa52492323\", \"remoting_unittests\": \"d0ecd26cf964f2a0f5c08710222fab04fb0d710d\", \"sbox_integration_tests\": \"d45ba21e08c9e70f45feb06eeea931dc50d56dc7\", \"sbox_unittests\": \"273c9bcb03b3181898bbc9451f77569dd3dbbbea\", \"sbox_validation_tests\": \"786e95c51bf0519cd4462eb660c474918f18583a\", \"service_manager_unittests\": \"30327064c62658cef278b57cd10ec6229d87d86f\", \"services_unittests\": \"9a6ca1e66c0abdcd54726addf2cc373f9dedba6b\", \"setup_unittests\": \"2a81c88814d061bb07c351f0ec07f11ee54c0af4\", \"shell_dialogs_unittests\": \"d621a9b59f5926f397eafb47ca2e0a9bb6cba99e\", \"skia_unittests\": \"5e8ea9bd3509d1d306c5132e3a73845dc0cf0bc8\", \"snapshot_unittests\": \"e0c1f43d9f02b6ed05bc7551f2eb85ca70223088\", \"sql_unittests\": \"465d2e510cbf2f7be3f5d9fd43dd47a0ca94b1ac\", \"storage_unittests\": \"9d942788861f81effadcec1bfbfa57fe4f5a153a\", \"sync_integration_tests\": \"d9b032334bd8320d3bf1fbcacebb00e0e5ff3c2a\", \"traffic_annotation_auditor_unittests\": \"84043274b40d333ec5db0325077b29290fdf5ce3\", \"ui_base_unittests\": \"9f051d83b9cae709d279a6e7df7ee97ab97aee1a\", \"ui_touch_selection_unittests\": \"1c0852346eb887ce731ef3d252fd017de9d3213c\", \"unit_tests\": \"874eae08e13dadcaea673ad1a60945b5190861e9\", \"updater_tests\": \"6eeb1da1231251e17ba4a9331405896eed2fd812\", \"url_unittests\": \"b6ca0c25ee1539f9ed8d230599f9baf87cab6ee7\", \"views_unittests\": \"94dcbd5a0bd03088e2a5571fbf5d467d0b803852\", \"viz_unittests\": \"4a07031d52a9f4982a327236db5140d98dc55d08\", \"vr_common_unittests\": \"ac7344c35b83db893d8d5b4d10f6d0de0359f2c4\", \"vr_pixeltests\": \"98a8f7a983c4c53c8964727dae3c9890fd342461\", \"weblayer_browsertests\": \"1dbb85b3431c9028f8ff049972c0c6a22f12314e\", \"weblayer_unittests\": \"81419c20581231dbc12976cfda9b48ca4b01ec22\", \"wm_unittests\": \"2a4ceaffbb720fac6f9f12ef2dd0aa6015162d92\", \"wtf_unittests\": \"f3a1dd2f0384a81607b2305519ffb0c386afd4ca\", \"zlib_unittests\": \"e8484b0ce7e0b127838496686d5f68d394d1c969\", \"zucchini_unittests\": \"a3ddfdddea2fcfe37db70f2fc5f57026d56255e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\"], \"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\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611187966475567",
"status": "COMPLETED",
"status_changed_ts": "1611191805624623",
"tags": [
"build_address:luci.chromium.ci/win-asan/16920",
"builder:win-asan",
"buildset:commit/git/b878cc85da6730d506e5c671b667be2b19466fd8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b878cc85da6730d506e5c671b667be2b19466fd8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022970748532869440",
"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/8857540686449784880/+/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:513b1ad33d664810",
"user_agent:luci-scheduler"
],
"updated_ts": "1611191805634767",
"url": "https://ci.chromium.org/b/8857540686449784880",
"utcnow_ts": "1611191807480788"
}
}
[01:16:47.500] Invocation finished in 1h4m5.852541778s with status SUCCEEDED