[08:29:57.345] New invocation is queued and will start shortly
[08:29:58.474] Starting the invocation (attempt 1)
[08:29:58.519] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:29:58.519] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:29:58.520] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029824833000098096",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e2e543afe40ab8b474cea5d63d6326c62c686980\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029824833000098096",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e2e543afe40ab8b474cea5d63d6326c62c686980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2e543afe40ab8b474cea5d63d6326c62c686980",
"gitiles_ref:refs/heads/master"
]
}
[08:29:59.574] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604651398647283",
"id": "8864394770970696528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e2e543afe40ab8b474cea5d63d6326c62c686980\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604651399231570",
"tags": [
"build_address:luci.chromium.ci/win-asan/15492",
"builder:win-asan",
"buildset:commit/git/e2e543afe40ab8b474cea5d63d6326c62c686980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2e543afe40ab8b474cea5d63d6326c62c686980",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029824833000098096",
"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/8864394770970696528/+/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": "1604651399231706",
"url": "https://ci.chromium.org/b/8864394770970696528",
"utcnow_ts": "1604651399516629"
}
}
[08:29:59.575] Task URL: https://ci.chromium.org/b/8864394770970696528
[08:29:59.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:2:0) after 1m0s
[08:30:07.707] Received PubSub notification, asking Buildbucket for the build status
[08:30:07.794] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:59.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:2:0)
[08:30:59.689] Timer tick, asking Buildbucket for the build status
[08:30:59.960] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:59.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:4:0) after 1m0s
[08:32:00.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:4:0)
[08:32:00.033] Timer tick, asking Buildbucket for the build status
[08:32:00.569] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:00.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:5:0) after 1m0s
[08:33:01.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:5:0)
[08:33:01.103] Timer tick, asking Buildbucket for the build status
[08:33:01.242] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:01.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:6:0) after 1m0s
[08:34:01.279] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:6:0)
[08:34:01.279] Timer tick, asking Buildbucket for the build status
[08:34:01.584] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:01.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:7:0) after 1m0s
[08:35:01.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:7:0)
[08:35:01.779] Timer tick, asking Buildbucket for the build status
[08:35:01.904] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:01.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:8:0) after 1m0s
[08:36:02.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:8:0)
[08:36:02.191] Timer tick, asking Buildbucket for the build status
[08:36:02.278] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:02.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:9:0) after 1m0s
[08:37:02.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:9:0)
[08:37:02.334] Timer tick, asking Buildbucket for the build status
[08:37:02.407] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:02.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:10:0) after 1m0s
[08:38:02.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:10:0)
[08:38:02.504] Timer tick, asking Buildbucket for the build status
[08:38:02.583] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:02.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:11:0) after 1m0s
[08:39:02.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:11:0)
[08:39:02.640] Timer tick, asking Buildbucket for the build status
[08:39:02.717] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:02.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:12:0) after 1m0s
[08:40:02.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:12:0)
[08:40:02.739] Timer tick, asking Buildbucket for the build status
[08:40:02.949] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:02.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:13:0) after 1m0s
[08:41:02.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:13:0)
[08:41:02.981] Timer tick, asking Buildbucket for the build status
[08:41:03.128] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:03.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:14:0) after 1m0s
[08:42:03.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:14:0)
[08:42:03.213] Timer tick, asking Buildbucket for the build status
[08:42:03.484] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:03.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:15:0) after 1m0s
[08:43:03.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:15:0)
[08:43:03.599] Timer tick, asking Buildbucket for the build status
[08:43:03.797] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:03.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:16:0) after 1m0s
[08:44:03.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:16:0)
[08:44:03.826] Timer tick, asking Buildbucket for the build status
[08:44:04.153] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:04.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:17:0) after 1m0s
[08:45:04.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:17:0)
[08:45:04.195] Timer tick, asking Buildbucket for the build status
[08:45:04.366] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:04.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:18:0) after 1m0s
[08:46:04.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:18:0)
[08:46:04.399] Timer tick, asking Buildbucket for the build status
[08:46:04.531] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:04.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:19:0) after 1m0s
[08:47:04.585] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:19:0)
[08:47:04.585] Timer tick, asking Buildbucket for the build status
[08:47:04.827] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:04.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:20:0) after 1m0s
[08:48:04.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:20:0)
[08:48:04.805] Timer tick, asking Buildbucket for the build status
[08:48:05.280] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:05.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:21:0) after 1m0s
[08:49:05.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:21:0)
[08:49:05.322] Timer tick, asking Buildbucket for the build status
[08:49:05.446] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:05.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:22:0) after 1m0s
[08:50:05.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:22:0)
[08:50:05.568] Timer tick, asking Buildbucket for the build status
[08:50:05.724] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:05.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:23:0) after 1m0s
[08:51:05.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:23:0)
[08:51:05.863] Timer tick, asking Buildbucket for the build status
[08:51:06.019] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:06.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:24:0) after 1m0s
[08:52:06.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:24:0)
[08:52:06.086] Timer tick, asking Buildbucket for the build status
[08:52:06.795] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:06.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:25:0) after 1m0s
[08:53:06.891] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:25:0)
[08:53:06.891] Timer tick, asking Buildbucket for the build status
[08:53:07.277] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:07.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:26:0) after 1m0s
[08:54:07.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:26:0)
[08:54:07.326] Timer tick, asking Buildbucket for the build status
[08:54:07.660] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:07.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:27:0) after 1m0s
[08:55:07.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:27:0)
[08:55:07.740] Timer tick, asking Buildbucket for the build status
[08:55:07.853] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:07.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:28:0) after 1m0s
[08:56:07.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:28:0)
[08:56:07.838] Timer tick, asking Buildbucket for the build status
[08:56:09.185] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:09.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:29:0) after 1m0s
[08:57:09.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:29:0)
[08:57:09.200] Timer tick, asking Buildbucket for the build status
[08:57:09.325] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:09.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:30:0) after 1m0s
[08:58:09.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:30:0)
[08:58:09.410] Timer tick, asking Buildbucket for the build status
[08:58:09.586] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:09.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:31:0) after 1m0s
[08:59:09.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:31:0)
[08:59:09.687] Timer tick, asking Buildbucket for the build status
[08:59:09.836] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:09.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:32:0) after 1m0s
[09:00:09.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:32:0)
[09:00:09.908] Timer tick, asking Buildbucket for the build status
[09:00:10.109] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:10.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:33:0) after 1m0s
[09:01:11.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:33:0)
[09:01:11.002] Timer tick, asking Buildbucket for the build status
[09:01:11.206] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:11.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:34:0) after 1m0s
[09:02:11.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:34:0)
[09:02:11.251] Timer tick, asking Buildbucket for the build status
[09:02:11.322] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:11.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:35:0) after 1m0s
[09:03:11.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:35:0)
[09:03:11.480] Timer tick, asking Buildbucket for the build status
[09:03:11.659] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:11.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:36:0) after 1m0s
[09:04:11.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:36:0)
[09:04:11.686] Timer tick, asking Buildbucket for the build status
[09:04:11.861] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:11.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:37:0) after 1m0s
[09:05:11.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:37:0)
[09:05:11.885] Timer tick, asking Buildbucket for the build status
[09:05:12.200] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:12.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:38:0) after 1m0s
[09:06:12.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:38:0)
[09:06:12.302] Timer tick, asking Buildbucket for the build status
[09:06:12.446] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:12.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:39:0) after 1m0s
[09:07:12.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:39:0)
[09:07:12.513] Timer tick, asking Buildbucket for the build status
[09:07:12.603] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:12.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:40:0) after 1m0s
[09:08:12.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:40:0)
[09:08:12.728] Timer tick, asking Buildbucket for the build status
[09:08:12.816] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:12.816] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:41:0) after 1m0s
[09:09:12.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:41:0)
[09:09:12.954] Timer tick, asking Buildbucket for the build status
[09:09:13.072] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:13.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:42:0) after 1m0s
[09:10:13.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:42:0)
[09:10:13.149] Timer tick, asking Buildbucket for the build status
[09:10:13.284] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:13.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:43:0) after 1m0s
[09:11:13.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:43:0)
[09:11:13.345] Timer tick, asking Buildbucket for the build status
[09:11:13.402] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:13.402] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:44:0) after 1m0s
[09:12:13.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:44:0)
[09:12:13.440] Timer tick, asking Buildbucket for the build status
[09:12:13.613] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:13.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:45:0) after 1m0s
[09:13:13.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:45:0)
[09:13:13.615] Timer tick, asking Buildbucket for the build status
[09:13:13.808] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:13.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:46:0) after 1m0s
[09:14:13.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:46:0)
[09:14:13.808] Timer tick, asking Buildbucket for the build status
[09:14:14.479] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:14.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:47:0) after 1m0s
[09:15:14.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:47:0)
[09:15:14.508] Timer tick, asking Buildbucket for the build status
[09:15:14.643] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:14.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:48:0) after 1m0s
[09:16:14.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:48:0)
[09:16:14.710] Timer tick, asking Buildbucket for the build status
[09:16:14.839] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:14.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:49:0) after 1m0s
[09:17:14.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:49:0)
[09:17:14.913] Timer tick, asking Buildbucket for the build status
[09:17:15.124] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:15.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:50:0) after 1m0s
[09:18:15.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:50:0)
[09:18:15.177] Timer tick, asking Buildbucket for the build status
[09:18:15.264] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:15.264] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:51:0) after 1m0s
[09:19:15.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:51:0)
[09:19:15.322] Timer tick, asking Buildbucket for the build status
[09:19:15.751] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:15.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:52:0) after 1m0s
[09:20:15.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:52:0)
[09:20:15.776] Timer tick, asking Buildbucket for the build status
[09:20:15.946] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:15.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:53:0) after 1m0s
[09:21:16.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:53:0)
[09:21:16.028] Timer tick, asking Buildbucket for the build status
[09:21:16.418] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:16.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:54:0) after 1m0s
[09:22:16.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:54:0)
[09:22:16.441] Timer tick, asking Buildbucket for the build status
[09:22:16.533] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:16.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:55:0) after 1m0s
[09:23:16.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:55:0)
[09:23:16.785] Timer tick, asking Buildbucket for the build status
[09:23:17.349] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:17.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:56:0) after 1m0s
[09:24:17.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:56:0)
[09:24:17.457] Timer tick, asking Buildbucket for the build status
[09:24:17.551] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:17.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:57:0) after 1m0s
[09:25:17.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:57:0)
[09:25:17.663] Timer tick, asking Buildbucket for the build status
[09:25:18.674] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:18.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:58:0) after 1m0s
[09:26:18.863] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:58:0)
[09:26:18.863] Timer tick, asking Buildbucket for the build status
[09:26:19.031] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:19.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:59:0) after 1m0s
[09:27:19.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:59:0)
[09:27:19.148] Timer tick, asking Buildbucket for the build status
[09:27:19.371] Build 8864394770970696528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:19.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029824833000098096:60:0) after 1m0s
[09:27:28.403] Received PubSub notification, asking Buildbucket for the build status
[09:27:28.486] Build 8864394770970696528: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:27:28.486] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604654846989716",
"created_by": "project:chromium",
"created_ts": "1604651398647283",
"id": "8864394770970696528",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e2e543afe40ab8b474cea5d63d6326c62c686980\"}}",
"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\": \"e2e543afe40ab8b474cea5d63d6326c62c686980\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-4-h4bf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15492, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"3dfaeeb00271b1e230e8cc03415cdbe7dd34efb6\", \"got_dawn_revision\": \"2b6b0f45ff0597a847d0da25485dce9e2278ee80\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"e2e543afe40ab8b474cea5d63d6326c62c686980\", \"got_revision_cp\": \"refs/heads/master@{#824779}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"612ded0654a027d64922448f136d125a9dc17956\", \"got_v8_revision\": \"e97171d2718ff00638282b995f8c46a917b20b6c\", \"got_v8_revision_cp\": \"refs/heads/8.8.242@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"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\": \"e2e543afe40ab8b474cea5d63d6326c62c686980\", \"swarm_hashes_refs/heads/master(at){#824779}_without_patch\": {\"absl_hardening_tests\": \"021eb9f2050bd0e26fbeab5c71f548cfcdc30966\", \"accessibility_unittests\": \"b36b19bb2328da3ff4bd36234cf94731e0cdd675\", \"angle_unittests\": \"1e4efef50286adf900038bde10053712fd957312\", \"app_shell_unittests\": \"b937f586c466780b48f0f9ce6d786fd22372147d\", \"aura_unittests\": \"52a0e1b1343f120c02c01cd53c69d30719b55dbf\", \"base_unittests\": \"48f12a61da28f4327bb52e269883246224d407c7\", \"base_util_unittests\": \"afa061f5e6b028e08827b6f427b25a4c2c46c752\", \"blink_common_unittests\": \"324c5c180a2e17316fc0a1a4a376de6b0034d020\", \"blink_fuzzer_unittests\": \"03e8fd9c7fc02dccc648c3dd6bd59ff1f98c6d6d\", \"blink_heap_unittests\": \"35701f7034053473f6e08fdfd228e35abab2a284\", \"blink_platform_unittests\": \"d71fd330c42530f23058199cd9a4b8aca3173f08\", \"blink_unittests\": \"d6a925208a09bd6c101b1a6b669e46e758d2f01b\", \"boringssl_crypto_tests\": \"5056205557a2380160de094ec920ef7714c91ff7\", \"boringssl_ssl_tests\": \"e90058c71db486e655aa9cea1b9cabb4d6b8b9f4\", \"browser_switcher_bho_unittests\": \"7fc9c32f3e9334fe952633c59c8f165db2b3e1c6\", \"browser_tests\": \"28de210c41a8e36380d8ab8e72695596a187d234\", \"capture_unittests\": \"22fb615dd9905a6b53f267d698790773f34c1047\", \"cast_unittests\": \"67534c1750df6743f9fb13a544cd2347ef1c9025\", \"cc_unittests\": \"a72f8dc6dabdae686dd01f42a8f50a188e58aaed\", \"chrome_app_unittests\": \"8673db74a064a91b1426d9e3b2d466e428fa5aa3\", \"chrome_cleaner_unittests\": \"481199c42c831bdb63ae393a312f3d414b17f1a3\", \"chrome_elf_unittests\": \"c02382eb109257407bfb7bbff512ee25d64c86c3\", \"chromedriver_unittests\": \"28c828cf49ef34cbabd0ccac9bc279475160cd86\", \"components_browsertests\": \"1f33ec0ac927046468ba200571c5fb93d880181b\", \"components_unittests\": \"d10171d8d74147ca1c004c9259d6ead75e2e8282\", \"compositor_unittests\": \"be3a68c4923e8015598251b0bb6f567b1706d73c\", \"content_browsertests\": \"dc3deb85695f624bc55421425347d9a9c2c5971e\", \"content_unittests\": \"449787ce6a1fa34009d5e44b5242319dd44d0a29\", \"courgette_unittests\": \"5addf8b2259453e0d76235a7262ac4da7fe56d0d\", \"crashpad_tests\": \"464e5d180c0077589fd179be33407c3f219e7bfc\", \"cronet_tests\": \"f603cf8baa7f5c5a71a436079aa01cf23770b6ac\", \"cronet_unittests\": \"bed7060292328b2315a087c7a3701f501281aa34\", \"crypto_unittests\": \"cf08f9b215137e7358cfedca640f088751a89b1c\", \"delayloads_unittests\": \"e77df10e4f37b1041c4bc577a4f59801dbae902e\", \"device_unittests\": \"554e6ce4ac67536e0df381c1c8d3118c1fb9f3c5\", \"display_unittests\": \"8890c5ab21d97b5dc22de93a8a8f2d4c65e1a394\", \"elevation_service_unittests\": \"32d7c966535d2fcdf04575d7ca41b5be6d64e136\", \"events_unittests\": \"c3079ffc13a94edf3523617248786b7d7d19aa56\", \"extensions_browsertests\": \"7301228292ce2e1ca059c3fccf0f42a349a68441\", \"extensions_unittests\": \"cb0b7f2eb1039b79668aaf8de2f041322917d664\", \"filesystem_service_unittests\": \"e1b9f99c7e7a2aea6c4c57d6343f5edd357665e0\", \"gcm_unit_tests\": \"4d1123221691df8d6b8063b00dc517076021752f\", \"gcp_unittests\": \"62d774813c155c0cc8f6facc06e9f55dcebc9baa\", \"gfx_unittests\": \"78ab4c36cb0dc0b0421f1d88b59f417411fe5895\", \"gin_unittests\": \"8ad18ad0f9a903c0a052ceeba128f296b6f3f85e\", \"google_apis_unittests\": \"eeefb58288c494a41e65390d13cff4ebc7544d3f\", \"gpu_unittests\": \"9ca0f6b3d5a01d9b5f81240abb4e82cd1bc7f32c\", \"gwp_asan_unittests\": \"ddddc663f0defbcf5a7516890f47b42dd7e370ed\", \"headless_browsertests\": \"2e6cb7cf1eaf3840f51d8415c41f4d703eb27bb9\", \"headless_unittests\": \"f8947f7540c0c541d599e00f53fee3c5e3512b5a\", \"install_static_unittests\": \"90078e9c6ce81087916dda0b00eefa444638f667\", \"installer_util_unittests\": \"baa3327ebd2b6b1dba7499abee36875c3e450719\", \"interactive_ui_tests\": \"7a7bfdb49cd332617f8a26ad4b75b9313073df44\", \"ipc_tests\": \"f6cce61119f6a66c36a10f478f3d3c3edbe01c36\", \"jingle_unittests\": \"8baec5cbc041fb689f2880d083e92a06adbf0ef1\", \"latency_unittests\": \"3def60e1d53e2534b48617363b0cc745da9e8b8d\", \"libjingle_xmpp_unittests\": \"c5ee23768e8428dadfcb16b82ac0d963779ce634\", \"liburlpattern_unittests\": \"262de4a4b73a2b0fe45d2143bb3decc3347d3d38\", \"media_blink_unittests\": \"bd9abcb5e24870be1d89a1c2d995d0380ba45c2d\", \"media_unittests\": \"a4eeafafb0e2dc1285edd4c0741f72896089a5c9\", \"message_center_unittests\": \"009eb22ae87ec41df4cf8c4083b8927e752847c9\", \"midi_unittests\": \"2475d9830dbe9ee8013aebf7462b253666703abb\", \"mojo_core_unittests\": \"55f7376627bad252e08b74d974c65f60f5962f75\", \"mojo_unittests\": \"e874646f8a3511e27b65d534402c737407e6347c\", \"nacl_loader_unittests\": \"c21b8c6a4c968bcc58dd159f25dc85e1e9c143d1\", \"native_theme_unittests\": \"d7758389676c5af7fad90ef4fff861bb0662df79\", \"net_unittests\": \"5638a81d4466fdc7f1aa741bd795f7968547179e\", \"notification_helper_unittests\": \"7e2e37eac9efc7f256082809453e58911b41234e\", \"pdf_unittests\": \"77c2c2c76109dbcd05cb946e8788944aced6fd62\", \"perfetto_unittests\": \"d24d9954a969e4cc5d7d278474b03a0ba65e52f7\", \"ppapi_unittests\": \"872812e68ccc448d3751640b3c3d924ebdd0e091\", \"printing_unittests\": \"a21fafe5af27a126ade019a656076481f2b0f71b\", \"remoting_unittests\": \"5bf550377b77d241ca258e85430e5eefbac0c96b\", \"sbox_integration_tests\": \"5f733291a5b6d0df8968c6e3ca6021f820277a45\", \"sbox_unittests\": \"cefdd0dc989ac5347e37cbddb407cf6c31584ebc\", \"sbox_validation_tests\": \"b74a233d52ca7a75ea17080e9d5c8c1abeee52af\", \"service_manager_unittests\": \"3e58fec5e0d18e3eebc884c4cfe9163bec1262ae\", \"services_unittests\": \"c36f52534acfec814e8d19c0c06a000da1bed34a\", \"setup_unittests\": \"d39aa68466f070a95b0bae077beeb54d72432241\", \"shell_dialogs_unittests\": \"e2d9f1e1a88e75dba9402bc4d50c90ca2f044a30\", \"skia_unittests\": \"0108145b8a552a258aa31534cd836c303c19ac89\", \"snapshot_unittests\": \"5892b0b21884b53a6ee72c6cd51e525cddec3d4d\", \"sql_unittests\": \"3a7d3ca2f2569e94c883bd0043ee21a798ad6834\", \"storage_unittests\": \"971d9d11f9860f0ee94ad709f4489c3617941171\", \"sync_integration_tests\": \"fd0c19f37dee16ec568040250dcf6e38c288d8eb\", \"traffic_annotation_auditor_unittests\": \"e6ccfd3b344f8ee42363d670ea4111585e286b03\", \"ui_base_unittests\": \"3f2004880de951ee579f13926f297f99155ae856\", \"ui_touch_selection_unittests\": \"4c93d3bf3d0e68434bab0e3246e14545c6bb6070\", \"unit_tests\": \"49dd5fa31a58b8b2c3c404a55b66b01a1058517d\", \"updater_tests\": \"8b3fa5f0ddd188482ef028685ec095459924cd64\", \"url_unittests\": \"4e075d6ef6100a1a82026b8ffd24ba06170250d3\", \"views_unittests\": \"26c17ec44545dbecf573077e5fa967d50a207ff4\", \"viz_unittests\": \"f05cf8508c27f97591ca70f9001cc039fc293e68\", \"vr_common_unittests\": \"27a8a078f20a0f864ba83a67adba73f741ffae02\", \"vr_pixeltests\": \"b9c4fdb970ff0c1aad6080202f735113cbbd027b\", \"weblayer_browsertests\": \"a35f26127afd366c63fcd61ffb93655142fca0f2\", \"weblayer_unittests\": \"118745e5f9cd90f99f8c72da6b97a526e04130dd\", \"wm_unittests\": \"74a2d6e744e93e882d8a54ba10c88645bdff225c\", \"wtf_unittests\": \"e18d3251bfdadf17659bc6a7f6db8e3e9d28cd36\", \"zlib_unittests\": \"7d55904f60c2b5f678a8df1021c4f036a2f4e245\", \"zucchini_unittests\": \"3b108dbdbc5ba51129b4a90f88d7659f9646fb87\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-4-h4bf\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"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": "1604651406357849",
"status": "COMPLETED",
"status_changed_ts": "1604654847432266",
"tags": [
"build_address:luci.chromium.ci/win-asan/15492",
"builder:win-asan",
"buildset:commit/git/e2e543afe40ab8b474cea5d63d6326c62c686980",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2e543afe40ab8b474cea5d63d6326c62c686980",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029824833000098096",
"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/8864394770970696528/+/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:4fb57ec18b1a4c10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604654847479461",
"url": "https://ci.chromium.org/b/8864394770970696528",
"utcnow_ts": "1604654848476411"
}
}
[09:27:28.486] Invocation finished in 57m31.158517275s with status SUCCEEDED