[10:35:46.940] New invocation is queued and will start shortly
[10:35:48.126] Starting the invocation (attempt 1)
[10:35:48.186] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:35:48.187] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:35:48.187] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027189604650071920",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"84080ed1e884b5bfe524b13a42204662c72af7fb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027189604650071920",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/84080ed1e884b5bfe524b13a42204662c72af7fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84080ed1e884b5bfe524b13a42204662c72af7fb",
"gitiles_ref:refs/heads/master"
]
}
[10:35:48.847] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1607164548326440",
"id": "8861759542533050544",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84080ed1e884b5bfe524b13a42204662c72af7fb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607164548747534",
"tags": [
"build_address:luci.chromium.ci/win-asan/16041",
"builder:win-asan",
"buildset:commit/git/84080ed1e884b5bfe524b13a42204662c72af7fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84080ed1e884b5bfe524b13a42204662c72af7fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027189604650071920",
"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/8861759542533050544/+/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": "1607164548747638",
"url": "https://ci.chromium.org/b/8861759542533050544",
"utcnow_ts": "1607164548840981"
}
}
[10:35:48.847] Task URL: https://ci.chromium.org/b/8861759542533050544
[10:35:48.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:2:0) after 1m0s
[10:35:53.517] Received PubSub notification, asking Buildbucket for the build status
[10:35:53.585] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:48.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:2:0)
[10:36:48.896] Timer tick, asking Buildbucket for the build status
[10:36:48.985] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:48.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:4:0) after 1m0s
[10:37:48.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:4:0)
[10:37:48.965] Timer tick, asking Buildbucket for the build status
[10:37:49.087] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:49.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:5:0) after 1m0s
[10:38:49.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:5:0)
[10:38:49.213] Timer tick, asking Buildbucket for the build status
[10:38:49.347] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:49.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:6:0) after 1m0s
[10:39:49.346] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:6:0)
[10:39:49.346] Timer tick, asking Buildbucket for the build status
[10:39:57.570] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:57.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:7:0) after 1m0s
[10:40:57.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:7:0)
[10:40:57.665] Timer tick, asking Buildbucket for the build status
[10:40:57.977] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:57.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:8:0) after 1m0s
[10:41:57.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:8:0)
[10:41:57.969] Timer tick, asking Buildbucket for the build status
[10:41:58.092] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:58.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:9:0) after 1m0s
[10:42:58.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:9:0)
[10:42:58.062] Timer tick, asking Buildbucket for the build status
[10:42:58.307] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:58.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:10:0) after 1m0s
[10:43:58.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:10:0)
[10:43:58.288] Timer tick, asking Buildbucket for the build status
[10:43:58.388] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:58.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:11:0) after 1m0s
[10:44:58.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:11:0)
[10:44:58.408] Timer tick, asking Buildbucket for the build status
[10:44:58.563] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:58.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:12:0) after 1m0s
[10:45:58.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:12:0)
[10:45:58.624] Timer tick, asking Buildbucket for the build status
[10:45:58.733] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:58.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:13:0) after 1m0s
[10:46:58.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:13:0)
[10:46:58.751] Timer tick, asking Buildbucket for the build status
[10:46:58.858] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:58.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:14:0) after 1m0s
[10:47:58.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:14:0)
[10:47:58.971] Timer tick, asking Buildbucket for the build status
[10:47:59.099] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:59.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:15:0) after 1m0s
[10:48:59.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:15:0)
[10:48:59.137] Timer tick, asking Buildbucket for the build status
[10:48:59.213] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:59.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:16:0) after 1m0s
[10:49:59.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:16:0)
[10:49:59.242] Timer tick, asking Buildbucket for the build status
[10:50:05.950] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:05.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:17:0) after 1m0s
[10:51:06.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:17:0)
[10:51:06.077] Timer tick, asking Buildbucket for the build status
[10:51:06.346] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:06.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:18:0) after 1m0s
[10:52:06.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:18:0)
[10:52:06.473] Timer tick, asking Buildbucket for the build status
[10:52:06.975] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:06.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:19:0) after 1m0s
[10:53:07.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:19:0)
[10:53:07.049] Timer tick, asking Buildbucket for the build status
[10:53:07.240] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:07.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:20:0) after 1m0s
[10:54:07.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:20:0)
[10:54:07.629] Timer tick, asking Buildbucket for the build status
[10:54:07.782] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:07.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:21:0) after 1m0s
[10:55:07.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:21:0)
[10:55:07.968] Timer tick, asking Buildbucket for the build status
[10:55:08.275] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:08.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:22:0) after 1m0s
[10:56:08.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:22:0)
[10:56:08.402] Timer tick, asking Buildbucket for the build status
[10:56:16.948] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:16.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:23:0) after 1m0s
[10:57:16.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:23:0)
[10:57:16.978] Timer tick, asking Buildbucket for the build status
[10:57:17.110] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:17.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:24:0) after 1m0s
[10:58:17.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:24:0)
[10:58:17.243] Timer tick, asking Buildbucket for the build status
[10:58:17.341] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:17.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:25:0) after 1m0s
[10:59:17.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:25:0)
[10:59:17.490] Timer tick, asking Buildbucket for the build status
[10:59:17.796] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:17.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:26:0) after 1m0s
[11:00:18.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:26:0)
[11:00:18.090] Timer tick, asking Buildbucket for the build status
[11:00:19.285] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:19.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:27:0) after 1m0s
[11:01:19.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:27:0)
[11:01:19.745] Timer tick, asking Buildbucket for the build status
[11:01:27.426] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:27.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:28:0) after 1m0s
[11:02:27.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:28:0)
[11:02:27.473] Timer tick, asking Buildbucket for the build status
[11:02:27.667] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:27.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:29:0) after 1m0s
[11:03:27.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:29:0)
[11:03:27.732] Timer tick, asking Buildbucket for the build status
[11:03:27.858] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:27.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:30:0) after 1m0s
[11:04:27.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:30:0)
[11:04:27.867] Timer tick, asking Buildbucket for the build status
[11:04:28.062] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:28.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:31:0) after 1m0s
[11:05:28.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:31:0)
[11:05:28.115] Timer tick, asking Buildbucket for the build status
[11:05:38.168] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:38.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:32:0) after 1m0s
[11:06:38.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:32:0)
[11:06:38.192] Timer tick, asking Buildbucket for the build status
[11:06:38.427] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:38.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:33:0) after 1m0s
[11:07:38.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:33:0)
[11:07:38.447] Timer tick, asking Buildbucket for the build status
[11:07:38.598] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:38.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:34:0) after 1m0s
[11:08:38.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:34:0)
[11:08:38.597] Timer tick, asking Buildbucket for the build status
[11:08:38.683] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:38.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:35:0) after 1m0s
[11:09:38.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:35:0)
[11:09:38.705] Timer tick, asking Buildbucket for the build status
[11:09:38.972] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:38.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:36:0) after 1m0s
[11:10:38.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:36:0)
[11:10:38.993] Timer tick, asking Buildbucket for the build status
[11:10:39.078] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:39.078] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:37:0) after 1m0s
[11:11:39.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:37:0)
[11:11:39.147] Timer tick, asking Buildbucket for the build status
[11:11:39.254] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:39.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:38:0) after 1m0s
[11:12:39.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:38:0)
[11:12:39.843] Timer tick, asking Buildbucket for the build status
[11:12:39.938] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:39.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:39:0) after 1m0s
[11:13:40.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:39:0)
[11:13:40.100] Timer tick, asking Buildbucket for the build status
[11:13:40.368] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:40.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:40:0) after 1m0s
[11:14:40.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:40:0)
[11:14:40.665] Timer tick, asking Buildbucket for the build status
[11:14:40.963] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:40.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:41:0) after 1m0s
[11:15:41.050] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:41:0)
[11:15:41.050] Timer tick, asking Buildbucket for the build status
[11:15:41.253] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:41.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:42:0) after 1m0s
[11:16:41.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:42:0)
[11:16:41.399] Timer tick, asking Buildbucket for the build status
[11:16:44.068] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:44.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:43:0) after 1m0s
[11:17:44.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:43:0)
[11:17:44.148] Timer tick, asking Buildbucket for the build status
[11:17:44.281] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:44.281] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:44:0) after 1m0s
[11:18:44.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:44:0)
[11:18:44.336] Timer tick, asking Buildbucket for the build status
[11:18:44.447] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:44.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:45:0) after 1m0s
[11:19:44.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:45:0)
[11:19:44.515] Timer tick, asking Buildbucket for the build status
[11:19:44.670] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:44.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:46:0) after 1m0s
[11:20:44.677] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:46:0)
[11:20:44.677] Timer tick, asking Buildbucket for the build status
[11:20:44.935] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:44.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:47:0) after 1m0s
[11:21:45.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:47:0)
[11:21:45.007] Timer tick, asking Buildbucket for the build status
[11:21:52.999] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:52.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:48:0) after 1m0s
[11:22:53.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:48:0)
[11:22:53.070] Timer tick, asking Buildbucket for the build status
[11:22:53.263] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:53.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:49:0) after 1m0s
[11:23:53.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:49:0)
[11:23:53.302] Timer tick, asking Buildbucket for the build status
[11:23:53.394] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:53.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:50:0) after 1m0s
[11:24:53.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:50:0)
[11:24:53.418] Timer tick, asking Buildbucket for the build status
[11:24:53.492] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:53.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:51:0) after 1m0s
[11:25:53.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:51:0)
[11:25:53.531] Timer tick, asking Buildbucket for the build status
[11:25:53.839] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:53.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:52:0) after 1m0s
[11:26:53.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:52:0)
[11:26:53.918] Timer tick, asking Buildbucket for the build status
[11:26:54.217] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:54.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:53:0) after 1m0s
[11:27:54.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:53:0)
[11:27:54.329] Timer tick, asking Buildbucket for the build status
[11:27:54.412] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:54.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:54:0) after 1m0s
[11:28:54.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:54:0)
[11:28:54.447] Timer tick, asking Buildbucket for the build status
[11:28:54.522] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:54.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:55:0) after 1m0s
[11:29:54.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:55:0)
[11:29:54.519] Timer tick, asking Buildbucket for the build status
[11:29:55.412] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:55.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:56:0) after 1m0s
[11:30:55.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:56:0)
[11:30:55.398] Timer tick, asking Buildbucket for the build status
[11:30:55.747] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:55.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:57:0) after 1m0s
[11:31:55.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:57:0)
[11:31:55.844] Timer tick, asking Buildbucket for the build status
[11:31:56.385] Build 8861759542533050544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:56.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027189604650071920:58:0) after 1m0s
[11:32:20.927] Received PubSub notification, asking Buildbucket for the build status
[11:32:21.191] Build 8861759542533050544: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:32:21.192] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1607167939066348",
"created_by": "project:chromium",
"created_ts": "1607164548326440",
"id": "8861759542533050544",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"84080ed1e884b5bfe524b13a42204662c72af7fb\"}}",
"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\": \"84080ed1e884b5bfe524b13a42204662c72af7fb\", \"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-10-eupo\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16041, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"251ba5cb119ff2fed0e861cbc9b096c45004c1fa\", \"got_dawn_revision\": \"c532048062befdee8bb57d18f01d3a3c403d49de\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"84080ed1e884b5bfe524b13a42204662c72af7fb\", \"got_revision_cp\": \"refs/heads/master@{#834013}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"f36e874ade1dbc6f5ddfc857753a892a8bb358c3\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"6c80aebd00360ee00c84c7b14422c5fe475d8916\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32774}\", \"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\": \"84080ed1e884b5bfe524b13a42204662c72af7fb\", \"swarm_hashes_refs/heads/master(at){#834013}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"ad49ae7e38e70e752ff64c114432e8efd94c66d2\", \"angle_unittests\": \"b1485a29af17d361aec21fe0249230f2f510b4b0\", \"app_shell_unittests\": \"01d9c502bff5314fdeb131b2e94f14b09c5cca80\", \"aura_unittests\": \"2e79ce7e59a5952569fe84d14653611f642e1205\", \"base_unittests\": \"470147db8cd2774dc39c47a92a85a9f7ee7a99a1\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"978840fcddd74acfeeb863bfc04d731794ebe478\", \"blink_fuzzer_unittests\": \"c8bf3100e02ff474bf78f79c6610eca6f05c163d\", \"blink_heap_unittests\": \"578b3c0a07fe1495e1912db64e7c30a21b6ea194\", \"blink_platform_unittests\": \"88172c31084858ef72463d2e14edc794392a3076\", \"blink_unittests\": \"d6e14bd27a3cbf9f02d8e1d5aaf67c6b56aa776d\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"5b13f4271458357938ec1567d3e254525a6b3522\", \"capture_unittests\": \"206a4c5a1a59da81cc129ea37022038738d65537\", \"cast_unittests\": \"48bee35bc2c61a024199349863c9690fff45d288\", \"cc_unittests\": \"bdbc51ef54161708865cef51b2845d1fd567828a\", \"chrome_app_unittests\": \"00a81922abac26db64242d8e8636af2c1ec568cb\", \"chrome_cleaner_unittests\": \"18dcb7e69469e53e9819614cdaf892c4dbc36453\", \"chrome_elf_unittests\": \"1bd88e9390581ca307d331b35176b1e223e532f8\", \"chromedriver_unittests\": \"bf7763d2199100a9a0c36f7ce7b19efa2f4f8826\", \"components_browsertests\": \"c510ce38017685db2db2e91f86dc7478d5704298\", \"components_unittests\": \"44705b45250077202c8d2730008c78c613ec6696\", \"compositor_unittests\": \"e477c59ec71f4d2f509bc796be3178fa3f22d0ea\", \"content_browsertests\": \"c16192f69421758a835fe3c2de95a22586275b15\", \"content_unittests\": \"e4f3773f1202a98f9244354be5e1dffc85fd828a\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"4565c0080c0172ea91e84a84ff4a741f630e8892\", \"cronet_unittests\": \"03caadc32f28e399f48e5d2340b2306a52c914e4\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"df28aba995262bb73a9e5f81198ee7f1460726cb\", \"device_unittests\": \"a27cd06a91f72cc2bc8342bb4423a8575784c0d4\", \"display_unittests\": \"d84b670b74fb98205884ca4994ebdc283097ef47\", \"elevation_service_unittests\": \"ddddb787d6949b18d097678edb3e07a3e35ef0d0\", \"events_unittests\": \"265aa501c3a8832fdda9ef4d6f1c105d68e5fec4\", \"extensions_browsertests\": \"e78f37d4d460b4d89dc03707c92720a2f5f53289\", \"extensions_unittests\": \"05c47c0132136cb6b8d94e6d205270e3c8dcf328\", \"filesystem_service_unittests\": \"87c588402629f4887312cd409b1dfd56b9f0cf4e\", \"gcm_unit_tests\": \"fd57c1a67e0c934968abdb388236ad2da5e4f6eb\", \"gcp_unittests\": \"a52f6ef1def32e93d1d19392ea066ac4cb51ce7f\", \"gfx_unittests\": \"c4046080910713aefe5aab1615546c3050746f0f\", \"gin_unittests\": \"fbf3943c36bed4e1976df797b070566cc15a4047\", \"google_apis_unittests\": \"d5f68ce071865e830f0111fdee39beb5f3a614e1\", \"gpu_unittests\": \"8fb5b72b5b873c35257e968e5eaec6528ae72fca\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"59cb7e6527413cffd4d628612279263470c322f5\", \"headless_unittests\": \"fca73a038311b79b4453cf3593814140b08aafee\", \"install_static_unittests\": \"9438137441b9277db93d9e173a68dd473eb18a0a\", \"installer_util_unittests\": \"16f6622615a497e8729376859f5eb4aa87e91012\", \"interactive_ui_tests\": \"ee0ac7e566c75fe2a39d97ff5dd752c77f5df4ef\", \"ipc_tests\": \"81d059792b909665c1bee45a1fe7267026dcf87c\", \"jingle_unittests\": \"b7eef6c86607f10c7d94fbc4a26956992fb3f2be\", \"latency_unittests\": \"3922f1bc775be43c7e249dea74b28042d88a40ac\", \"libjingle_xmpp_unittests\": \"855ecd4d8fbe212ec0aa2618e308d5a26a3bdf95\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"92275c5bcc53f55230b061b14d608fa5c7c6d5d7\", \"media_unittests\": \"1ed5f290de225d2d3108b5e18155bb137a76cf96\", \"message_center_unittests\": \"ff3743d96c9a0035a552490f2802f33fcc85a197\", \"midi_unittests\": \"bd5a85b3ce8a1038d4de01df2e69d2574b15ec17\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"5d342bd34c474a26fb15ea1ab66998359c6b8ced\", \"nacl_loader_unittests\": \"ffe51053061f08ad630993d25527e0c678f9ffb0\", \"native_theme_unittests\": \"d76b2603623f68ec83a39a3129c303cb92260d23\", \"net_unittests\": \"7b6897edd6ef6e2ad7df8852194c8a3ed01080d7\", \"notification_helper_unittests\": \"afaad66390c5d53c0bdfca22bb7885c6a5f9920f\", \"pdf_unittests\": \"43defb20d2de5865fd0086de960b10371eb9ff1c\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"258d647e0db3c578479ead0883b0818ee0fe1c2a\", \"printing_unittests\": \"3bbb858f0e1a5e37ffa9b212fc9e61abebf59c72\", \"remoting_unittests\": \"74a98580826f1ce24a5cc58ec8fb62c7cc32d600\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"6d0a84d77e18f1d77a29e94c1d0a52a99b8b6a11\", \"services_unittests\": \"0f6c7b88067af0a699efee62e0dbbded4b12e7fc\", \"setup_unittests\": \"c9d277dcd32dec7ec7f587a974d2e2130b7f06c2\", \"shell_dialogs_unittests\": \"5b77b8854d74c20286293508503ff1fda714150d\", \"skia_unittests\": \"ede3368563d6b7002ef74a717593f4e4523e87ea\", \"snapshot_unittests\": \"4a9abef41a1189c8decb9e46d6f3f4f7aeae44b8\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"b9e438a6cf1d0ba298892beca3ce17b54a4e28de\", \"sync_integration_tests\": \"cd6c5aefa9c184bc6b16ed232e5314a3f95ab778\", \"traffic_annotation_auditor_unittests\": \"aa152cecf2a15987662c6627d857dea0fa112d0e\", \"ui_base_unittests\": \"d8a04c2cde7cb89a1b1aca7e2696fa6c6d191218\", \"ui_touch_selection_unittests\": \"66e48975d67769b8a75256be7eef4a16921a8c08\", \"unit_tests\": \"3199a8d5af94bb84e21e18dd0e4f07ca64a6a4c9\", \"updater_tests\": \"544ec087b8c4b51fadf0fd794f83ba3fb9876202\", \"url_unittests\": \"79bfaf70d0f060f3f9d1db725434db9ba5fdde75\", \"views_unittests\": \"3c284dddd92278c6074883066c5490d639c5a2da\", \"viz_unittests\": \"58a6cc9cb65609b291cb360b9988dac527e23917\", \"vr_common_unittests\": \"d8530145ae9861d5a1ed596f4b87465e3dea1953\", \"vr_pixeltests\": \"d51cfdb8f2793e910a8ab160c76f909d204b3b06\", \"weblayer_browsertests\": \"348563dac0ff6c3a5c2619804f857c8c780e7c86\", \"weblayer_unittests\": \"db7b27a49277409bf3e2587030f6663fdaf4eaf9\", \"wm_unittests\": \"ee0b542e5ec9fa7809412cc7e535e92f81d20321\", \"wtf_unittests\": \"8b40c667938045b1009e7adb8b06b7eb2681b4fa\", \"zlib_unittests\": \"69307228fd0317f2dd0ffa2574e71f6e04b426a1\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-10-eupo\"], \"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\": [\"5640-d0eb544\"], \"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": "1607164552744894",
"status": "COMPLETED",
"status_changed_ts": "1607167939791071",
"tags": [
"build_address:luci.chromium.ci/win-asan/16041",
"builder:win-asan",
"buildset:commit/git/84080ed1e884b5bfe524b13a42204662c72af7fb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/84080ed1e884b5bfe524b13a42204662c72af7fb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027189604650071920",
"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/8861759542533050544/+/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:504b4a5f48b7b210",
"user_agent:luci-scheduler"
],
"updated_ts": "1607167939817725",
"url": "https://ci.chromium.org/b/8861759542533050544",
"utcnow_ts": "1607167941169874"
}
}
[11:32:21.192] Invocation finished in 56m34.26815829s with status SUCCEEDED