[00:30:27.406] New invocation is queued and will start shortly
[00:30:28.684] Starting the invocation (attempt 1)
[00:30:28.781] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:30:28.781] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:30:28.782] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028586642948423216",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"02e3f8f0ea232eac074c12513c5ce3b9e5e42736\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028586642948423216",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/02e3f8f0ea232eac074c12513c5ce3b9e5e42736",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02e3f8f0ea232eac074c12513c5ce3b9e5e42736",
"gitiles_ref:refs/heads/master"
]
}
[00:30:30.060] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605832228942773",
"id": "8863156580663062064",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"02e3f8f0ea232eac074c12513c5ce3b9e5e42736\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605832229522197",
"tags": [
"build_address:luci.chromium.ci/win-asan/15730",
"builder:win-asan",
"buildset:commit/git/02e3f8f0ea232eac074c12513c5ce3b9e5e42736",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02e3f8f0ea232eac074c12513c5ce3b9e5e42736",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028586642948423216",
"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/8863156580663062064/+/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": "1605832229522336",
"url": "https://ci.chromium.org/b/8863156580663062064",
"utcnow_ts": "1605832230040486"
}
}
[00:30:30.060] Task URL: https://ci.chromium.org/b/8863156580663062064
[00:30:30.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:2:0) after 1m0s
[00:30:43.212] Received PubSub notification, asking Buildbucket for the build status
[00:30:44.128] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:30.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:2:0)
[00:31:30.089] Timer tick, asking Buildbucket for the build status
[00:31:30.181] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:31:30.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:4:0) after 1m0s
[00:32:30.221] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:4:0)
[00:32:30.221] Timer tick, asking Buildbucket for the build status
[00:32:30.338] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:32:30.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:5:0) after 1m0s
[00:33:30.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:5:0)
[00:33:30.379] Timer tick, asking Buildbucket for the build status
[00:33:30.497] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:33:30.497] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:6:0) after 1m0s
[00:34:30.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:6:0)
[00:34:30.544] Timer tick, asking Buildbucket for the build status
[00:34:30.636] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:34:30.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:7:0) after 1m0s
[00:35:30.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:7:0)
[00:35:30.694] Timer tick, asking Buildbucket for the build status
[00:35:30.823] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:35:30.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:8:0) after 1m0s
[00:36:30.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:8:0)
[00:36:30.930] Timer tick, asking Buildbucket for the build status
[00:36:31.407] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:36:31.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:9:0) after 1m0s
[00:37:31.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:9:0)
[00:37:31.505] Timer tick, asking Buildbucket for the build status
[00:37:31.578] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:37:31.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:10:0) after 1m0s
[00:38:31.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:10:0)
[00:38:31.647] Timer tick, asking Buildbucket for the build status
[00:38:32.839] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:38:32.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:11:0) after 1m0s
[00:39:32.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:11:0)
[00:39:32.926] Timer tick, asking Buildbucket for the build status
[00:39:33.112] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:39:33.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:12:0) after 1m0s
[00:40:33.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:12:0)
[00:40:33.140] Timer tick, asking Buildbucket for the build status
[00:40:33.253] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:40:33.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:13:0) after 1m0s
[00:41:33.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:13:0)
[00:41:33.353] Timer tick, asking Buildbucket for the build status
[00:41:33.481] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:41:33.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:14:0) after 1m0s
[00:42:33.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:14:0)
[00:42:33.576] Timer tick, asking Buildbucket for the build status
[00:42:33.641] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:42:33.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:15:0) after 1m0s
[00:43:33.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:15:0)
[00:43:33.705] Timer tick, asking Buildbucket for the build status
[00:43:33.817] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:43:33.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:16:0) after 1m0s
[00:44:33.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:16:0)
[00:44:33.894] Timer tick, asking Buildbucket for the build status
[00:44:34.113] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:44:34.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:17:0) after 1m0s
[00:45:34.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:17:0)
[00:45:34.203] Timer tick, asking Buildbucket for the build status
[00:45:34.277] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:45:34.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:18:0) after 1m0s
[00:46:34.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:18:0)
[00:46:34.301] Timer tick, asking Buildbucket for the build status
[00:46:34.471] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:46:34.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:19:0) after 1m0s
[00:47:34.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:19:0)
[00:47:34.510] Timer tick, asking Buildbucket for the build status
[00:47:34.653] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:47:34.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:20:0) after 1m0s
[00:48:34.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:20:0)
[00:48:34.656] Timer tick, asking Buildbucket for the build status
[00:48:35.476] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:48:35.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:21:0) after 1m0s
[00:49:35.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:21:0)
[00:49:35.493] Timer tick, asking Buildbucket for the build status
[00:49:35.578] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:49:35.578] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:22:0) after 1m0s
[00:50:35.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:22:0)
[00:50:35.600] Timer tick, asking Buildbucket for the build status
[00:50:36.346] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:50:36.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:23:0) after 1m0s
[00:51:36.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:23:0)
[00:51:36.372] Timer tick, asking Buildbucket for the build status
[00:51:36.836] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:51:36.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:24:0) after 1m0s
[00:52:36.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:24:0)
[00:52:36.912] Timer tick, asking Buildbucket for the build status
[00:52:37.094] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:52:37.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:25:0) after 1m0s
[00:53:37.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:25:0)
[00:53:37.130] Timer tick, asking Buildbucket for the build status
[00:53:37.362] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:53:37.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:26:0) after 1m0s
[00:54:37.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:26:0)
[00:54:37.383] Timer tick, asking Buildbucket for the build status
[00:54:37.569] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:54:37.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:27:0) after 1m0s
[00:55:37.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:27:0)
[00:55:37.613] Timer tick, asking Buildbucket for the build status
[00:55:37.832] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:55:37.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:28:0) after 1m0s
[00:56:37.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:28:0)
[00:56:37.855] Timer tick, asking Buildbucket for the build status
[00:56:38.015] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:56:38.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:29:0) after 1m0s
[00:57:38.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:29:0)
[00:57:38.039] Timer tick, asking Buildbucket for the build status
[00:57:38.488] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:57:38.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:30:0) after 1m0s
[00:58:38.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:30:0)
[00:58:38.489] Timer tick, asking Buildbucket for the build status
[00:58:38.670] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:58:38.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:31:0) after 1m0s
[00:59:38.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:31:0)
[00:59:38.704] Timer tick, asking Buildbucket for the build status
[00:59:38.843] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:59:38.843] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:32:0) after 1m0s
[01:00:38.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:32:0)
[01:00:38.893] Timer tick, asking Buildbucket for the build status
[01:00:41.046] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:00:41.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:33:0) after 1m0s
[01:01:41.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:33:0)
[01:01:41.118] Timer tick, asking Buildbucket for the build status
[01:01:41.644] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:01:41.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:34:0) after 1m0s
[01:02:41.675] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:34:0)
[01:02:41.675] Timer tick, asking Buildbucket for the build status
[01:02:41.755] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:02:41.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:35:0) after 1m0s
[01:03:41.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:35:0)
[01:03:41.829] Timer tick, asking Buildbucket for the build status
[01:03:41.962] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:03:41.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:36:0) after 1m0s
[01:04:41.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:36:0)
[01:04:41.984] Timer tick, asking Buildbucket for the build status
[01:04:42.159] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:04:42.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:37:0) after 1m0s
[01:05:42.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:37:0)
[01:05:42.157] Timer tick, asking Buildbucket for the build status
[01:05:42.224] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:42.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:38:0) after 1m0s
[01:06:42.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:38:0)
[01:06:42.264] Timer tick, asking Buildbucket for the build status
[01:06:42.380] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:42.380] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:39:0) after 1m0s
[01:07:42.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:39:0)
[01:07:42.423] Timer tick, asking Buildbucket for the build status
[01:07:42.601] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:42.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:40:0) after 1m0s
[01:08:42.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:40:0)
[01:08:42.632] Timer tick, asking Buildbucket for the build status
[01:08:43.183] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:43.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:41:0) after 1m0s
[01:09:43.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:41:0)
[01:09:43.216] Timer tick, asking Buildbucket for the build status
[01:09:43.908] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:43.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:42:0) after 1m0s
[01:10:43.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:42:0)
[01:10:43.982] Timer tick, asking Buildbucket for the build status
[01:10:44.191] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:44.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:43:0) after 1m0s
[01:11:44.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:43:0)
[01:11:44.206] Timer tick, asking Buildbucket for the build status
[01:11:44.931] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:44.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:44:0) after 1m0s
[01:12:44.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:44:0)
[01:12:44.932] Timer tick, asking Buildbucket for the build status
[01:12:45.024] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:45.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:45:0) after 1m0s
[01:13:45.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:45:0)
[01:13:45.083] Timer tick, asking Buildbucket for the build status
[01:13:45.177] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:45.177] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:46:0) after 1m0s
[01:14:45.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:46:0)
[01:14:45.185] Timer tick, asking Buildbucket for the build status
[01:14:45.317] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:45.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:47:0) after 1m0s
[01:15:45.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:47:0)
[01:15:45.375] Timer tick, asking Buildbucket for the build status
[01:15:45.546] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:45.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:48:0) after 1m0s
[01:16:45.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:48:0)
[01:16:45.568] Timer tick, asking Buildbucket for the build status
[01:16:45.866] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:45.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:49:0) after 1m0s
[01:17:45.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:49:0)
[01:17:45.853] Timer tick, asking Buildbucket for the build status
[01:17:46.039] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:46.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:50:0) after 1m0s
[01:18:46.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:50:0)
[01:18:46.086] Timer tick, asking Buildbucket for the build status
[01:18:46.316] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:46.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:51:0) after 1m0s
[01:19:46.344] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:51:0)
[01:19:46.344] Timer tick, asking Buildbucket for the build status
[01:19:46.550] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:46.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:52:0) after 1m0s
[01:20:46.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:52:0)
[01:20:46.545] Timer tick, asking Buildbucket for the build status
[01:20:46.844] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:46.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:53:0) after 1m0s
[01:21:46.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:53:0)
[01:21:46.867] Timer tick, asking Buildbucket for the build status
[01:21:46.947] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:46.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:54:0) after 1m0s
[01:22:46.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:54:0)
[01:22:46.977] Timer tick, asking Buildbucket for the build status
[01:22:47.059] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:47.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:55:0) after 1m0s
[01:23:47.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:55:0)
[01:23:47.085] Timer tick, asking Buildbucket for the build status
[01:23:47.158] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:47.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:56:0) after 1m0s
[01:24:47.192] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:56:0)
[01:24:47.192] Timer tick, asking Buildbucket for the build status
[01:24:47.303] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:47.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:57:0) after 1m0s
[01:25:47.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:57:0)
[01:25:47.334] Timer tick, asking Buildbucket for the build status
[01:25:47.493] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:47.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:58:0) after 1m0s
[01:26:47.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:58:0)
[01:26:47.477] Timer tick, asking Buildbucket for the build status
[01:26:47.580] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:47.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:59:0) after 1m0s
[01:27:47.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:59:0)
[01:27:47.660] Timer tick, asking Buildbucket for the build status
[01:27:47.756] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:47.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:60:0) after 1m0s
[01:28:47.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:60:0)
[01:28:47.802] Timer tick, asking Buildbucket for the build status
[01:28:47.880] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:47.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:61:0) after 1m0s
[01:29:47.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:61:0)
[01:29:47.973] Timer tick, asking Buildbucket for the build status
[01:29:48.487] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:48.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:62:0) after 1m0s
[01:30:48.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:62:0)
[01:30:48.572] Timer tick, asking Buildbucket for the build status
[01:30:49.234] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:49.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:63:0) after 1m0s
[01:31:49.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:63:0)
[01:31:49.312] Timer tick, asking Buildbucket for the build status
[01:31:49.551] Build 8863156580663062064: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:49.551] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028586642948423216:64:0) after 1m0s
[01:31:54.826] Received PubSub notification, asking Buildbucket for the build status
[01:31:54.915] Build 8863156580663062064: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[01:31:54.915] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605835913315205",
"created_by": "project:chromium",
"created_ts": "1605832228942773",
"id": "8863156580663062064",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"02e3f8f0ea232eac074c12513c5ce3b9e5e42736\"}}",
"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\": \"02e3f8f0ea232eac074c12513c5ce3b9e5e42736\", \"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-7rud\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15730, \"clang_revision\": \"llvmorg-12-init-11780-g6ef07111-1\", \"got_angle_revision\": \"46107d3ece07f5cd1946b1013f69c110c0229a93\", \"got_dawn_revision\": \"1a14e7e0244114fe737caf46e3a2690355aceafe\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"02e3f8f0ea232eac074c12513c5ce3b9e5e42736\", \"got_revision_cp\": \"refs/heads/master@{#829448}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"de9e649438ed3cc2ecf5c30ebf08f033d01270e6\", \"got_v8_revision\": \"94b2c943f7f37e0450e7c5d029fbd22a9d7f6946\", \"got_v8_revision_cp\": \"refs/heads/8.9.14@{#1}\", \"got_webrtc_revision\": \"afaca889d46b2c493de34a8f0771c89dea17bb1a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32614}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"mirrored_builders\": [\"tryserver.chromium.win:win-asan\"], \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"02e3f8f0ea232eac074c12513c5ce3b9e5e42736\", \"swarm_hashes_refs/heads/master(at){#829448}_without_patch\": {\"absl_hardening_tests\": \"24c8e494041c5d0ceed80c2598ef6407cec7eaf4\", \"accessibility_unittests\": \"6e2b3a3d403e90bef5622e9682ede7a7c32d1bdb\", \"angle_unittests\": \"6a0deed9fc80c669be7d4075d8872415796ed7ec\", \"app_shell_unittests\": \"b78920c83ca9ee921a1c51c3f55501efc0866915\", \"aura_unittests\": \"79b51d94bbd3a2f75631e52a0566f8ec20a50884\", \"base_unittests\": \"38779b92896e3f331834b8bf3f4abfb3915842f5\", \"base_util_unittests\": \"632792babb9902c6f0cd90c898fa8d30f8e203b6\", \"blink_common_unittests\": \"a4fc127a1f2f4d9ec8190d93624569c61b74abb9\", \"blink_fuzzer_unittests\": \"fbd102b329abe6c13a9053c0c5f88c70280bb03f\", \"blink_heap_unittests\": \"1d680ce19f8d6a861e78cb90ab4ce06e464bc96c\", \"blink_platform_unittests\": \"f4baa15466df7f70db2afbb5ee8fe476c7d5eb5c\", \"blink_unittests\": \"81567adb4bdbd97d9b4575bd969c7fe28a2856ae\", \"boringssl_crypto_tests\": \"69c7e0ebf1c4707d791eaf5efb130105d49272aa\", \"boringssl_ssl_tests\": \"d6271ba9489901116fcaa563ace77b601dbd8ac8\", \"browser_switcher_bho_unittests\": \"2ed4593b60cdd4cc9952b77426fa3fc719138be4\", \"browser_tests\": \"7f5ac48864b97fa6440027147e1a9ccef7c27d69\", \"capture_unittests\": \"f767d9130cc29cc7f2f5705de0973b42b3b78b32\", \"cast_unittests\": \"8fb7d4e97607f7196aff0719405a789967d9e9b1\", \"cc_unittests\": \"1189bdc72939745f48d05d0f109e6944dbb3efdb\", \"chrome_app_unittests\": \"fe79dc36d833f7b90fc13c09cb5c9ccb2584b125\", \"chrome_cleaner_unittests\": \"17ee955b1282946b2ed44826201b129dabffcc4d\", \"chrome_elf_unittests\": \"f8f4450feb19b05af7fd9a3d40a623d8a337539e\", \"chromedriver_unittests\": \"3548f62c5dc2227a174460a4cf696012d0f4b930\", \"components_browsertests\": \"d016e254bdbae014290bf180823bc5e517c7aeee\", \"components_unittests\": \"71fb56f5de4e9eedfa3a9f4cb8c2e4d0b13ac16f\", \"compositor_unittests\": \"d64a3ad8550d32deaab3eb3e98e959da1e88bff9\", \"content_browsertests\": \"ad302cd20d1bb47dff7d8035ab75b38dced9d675\", \"content_unittests\": \"412875b7a8d8a328013b690b7d95ec6a8e97f7d2\", \"courgette_unittests\": \"4ecf9cac313e98ba1e2623f7934965c225cfc9c4\", \"crashpad_tests\": \"3e59851d63bb9215838a2920eca8af14ed9225fa\", \"cronet_tests\": \"e1e02943291b3fe2724eab204169a25da7e40ebf\", \"cronet_unittests\": \"36af7cd3b9e9216e73c011f99227b61937fdf2a8\", \"crypto_unittests\": \"7e4ad66a999915a6af2533075e5b95f2ed24f79c\", \"delayloads_unittests\": \"e546c2dddda794b6fec63d2cfbb4f782edc1d80c\", \"device_unittests\": \"7c688af5694806bb0e84a5fd478f3185da8abd6c\", \"display_unittests\": \"f45d202b5cb5b4c2be4b7411632b5e7b9832bb73\", \"elevation_service_unittests\": \"247d876912697bc91b88d481f31c6d85b39724e2\", \"events_unittests\": \"a2ed322d40062fb812d631b2433d10948358ba38\", \"extensions_browsertests\": \"ab30069a587ec4844533d80526c5bb0216461dc3\", \"extensions_unittests\": \"f41b85be1f4d913f04ea4d4bf4892b2ece085a60\", \"filesystem_service_unittests\": \"dec09e40581b9164306497bed570320983c67819\", \"gcm_unit_tests\": \"a64ec5c43e8ff58b7648ce49faadc98ed2f320ed\", \"gcp_unittests\": \"8fe593ade24c70f0bcad4003d6d7a10fc581690d\", \"gfx_unittests\": \"2c7b365fc27b30780719ac4822f1511c3c8cc551\", \"gin_unittests\": \"92174b60da0c4f59a9aa38b633688f9d6395d786\", \"google_apis_unittests\": \"90c27e0842ed6eaa363ce2913220607bae9ccd7b\", \"gpu_unittests\": \"46dfea55515332e80e537aa4c67c506c41468633\", \"gwp_asan_unittests\": \"4801a3cf65e574f8ea0e9cfa29be0f2c48518bd9\", \"headless_browsertests\": \"468e0c3ae457b729d08e350233ee3c530d57710b\", \"headless_unittests\": \"f81a073d8135850f9c2c3450505282c33cf5302f\", \"install_static_unittests\": \"cc7f963a9a849cc33a46cc2b80f579d706baa4a7\", \"installer_util_unittests\": \"d1f9d422b64a59131722e58281321c3b030e829a\", \"interactive_ui_tests\": \"a5ba57d25192aeba7532324b89918137e94e0800\", \"ipc_tests\": \"2b8f4d7d5ed433f8dfe33548e685dec8acbcb42b\", \"jingle_unittests\": \"cc287c731248e3e91ca9868d0f1a86e98bd5addd\", \"latency_unittests\": \"7099df6bc5e0e47b3ebccb0cf30fc285c54d133e\", \"libjingle_xmpp_unittests\": \"d8c044b3cce6aabb824bff3c1d8a29c365eddf0f\", \"liburlpattern_unittests\": \"f7f3b3d39cb821345e4b8e91cf997304d45f48c7\", \"media_blink_unittests\": \"1cd4fd0124f9f95caaa6bb0977d7ee44a63d1236\", \"media_unittests\": \"3a869a0fecee2175f347c3b98dcf8957234bd25d\", \"message_center_unittests\": \"654e0b01ec287ea4927283c0cf6165bcbbec6c45\", \"midi_unittests\": \"a5152d257f7cbbdb5cde4b73be1e5f62e80ff08a\", \"mojo_core_unittests\": \"04ea2a383cad0ebd23edbbc06ad3e6ec34399da2\", \"mojo_unittests\": \"1d494661b45e2b6a472605b381f0f906bf7936d9\", \"nacl_loader_unittests\": \"0a1cb165b11eabab57e7ca9c381203681472b4ef\", \"native_theme_unittests\": \"48f98dc272b3114961251b384a9076153aa25380\", \"net_unittests\": \"d62838aa432f9b195000923cb491f66ee2ee3c67\", \"notification_helper_unittests\": \"d877e24a177b5b3e8b1b7c307c284b3e84f844f8\", \"pdf_unittests\": \"e033f4db9b539bdf596af3b60d2cdfd43c0e512d\", \"perfetto_unittests\": \"04c9a8565e4390cb35ac1691e389257598b3bf69\", \"ppapi_unittests\": \"1f6b1e51fdec60ad1422472651d80fc26289877d\", \"printing_unittests\": \"7b11cd2c7721be4bec095664b760d2527b3950bf\", \"remoting_unittests\": \"a25e805aced67022dfd91fbc1ef2d242f2e8b9cb\", \"sbox_integration_tests\": \"9e89b269e4f8efb54cf1ad8430964df921777d4e\", \"sbox_unittests\": \"88ebbf36601784b1e0c3bd1acc47b938a2f808e4\", \"sbox_validation_tests\": \"cd4d9f6b4cef80326a36b0583100a59e32230585\", \"service_manager_unittests\": \"bc27b89f9b77eae783c894ffb64416916e34b156\", \"services_unittests\": \"c7d70b62c14121414af011213c6eaea8f8f2863d\", \"setup_unittests\": \"9335031c0c527fc73c7214541132ac8e628a0c25\", \"shell_dialogs_unittests\": \"0b08e2f12676d7054e2a138700ee391935721efe\", \"skia_unittests\": \"669ee4959b99c5f90568567192aa35b5db3e5f29\", \"snapshot_unittests\": \"f23458b0c876d41f0a0c001af417385b4b24fec4\", \"sql_unittests\": \"29746d85f9169af4f1c04772acd04e937a448400\", \"storage_unittests\": \"31dbb738842d3ac9b36d9620fba6baa52a6d0f66\", \"sync_integration_tests\": \"dc68ecf9a4b13a0e2d3883612ac4d36309b66d7c\", \"traffic_annotation_auditor_unittests\": \"91354eb5221ad863a85dbb5dc499746b3c40f61e\", \"ui_base_unittests\": \"cf14cfc3da00774c775fb3c78fd9d9e7bdc08fa1\", \"ui_touch_selection_unittests\": \"7c8198478e15a6298260edfbb0f311820175c934\", \"unit_tests\": \"f95d6bd7b317e033d3bf8aaf17f65ddf7a6dd9ca\", \"updater_tests\": \"0961557847c7a42d62e591cc4ed978ea9f9422a7\", \"url_unittests\": \"5b73d64fe887186b2f951f60bccdf7539126fdd3\", \"views_unittests\": \"679b6171775f190d3442a2cbe1d62e67deec9f22\", \"viz_unittests\": \"f4b44e0b26ed19afdcf2af2a233e15f2fc1a6a11\", \"vr_common_unittests\": \"75d95e4bd4338ae963fae30b724ff7ffefbc1277\", \"vr_pixeltests\": \"608971fb35e9aa89de34e880646b6c8cd19c899e\", \"weblayer_browsertests\": \"6d4a2a69939a8552c68568740cdc02d9f898ae90\", \"weblayer_unittests\": \"f42833ab34bd24514ee199841a1479b12a08ddf0\", \"wm_unittests\": \"6758fbe75797af5e7e62aa460ea1910fbc8cd4bc\", \"wtf_unittests\": \"14b2529d271db81b1f4b3d6588bb5eeb549de8d3\", \"zlib_unittests\": \"f57fd80a958116ef31769de3fae17ca8b9632581\", \"zucchini_unittests\": \"cc6614ae61d5f02a4b4bcbe1591f2f3267a2df55\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_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-7rud\"], \"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\": [\"5637-4d8b832\"], \"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": "1605832241615956",
"status": "COMPLETED",
"status_changed_ts": "1605835913879028",
"tags": [
"build_address:luci.chromium.ci/win-asan/15730",
"builder:win-asan",
"buildset:commit/git/02e3f8f0ea232eac074c12513c5ce3b9e5e42736",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02e3f8f0ea232eac074c12513c5ce3b9e5e42736",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028586642948423216",
"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/8863156580663062064/+/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:4ffbe0dac4179e10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605835913883584",
"url": "https://ci.chromium.org/b/8863156580663062064",
"utcnow_ts": "1605835914905321"
}
}
[01:31:54.915] Invocation finished in 1h1m27.535401202s with status SUCCEEDED