[16:31:02.163] New invocation is queued and will start shortly
[16:31:03.911] Starting the invocation (attempt 1)
[16:31:03.967] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:31:03.967] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:31:03.967] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027892029750334816",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4f5c636332ad0eb78e26394521ad124b0c7004ed\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027892029750334816",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4f5c636332ad0eb78e26394521ad124b0c7004ed",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f5c636332ad0eb78e26394521ad124b0c7004ed",
"gitiles_ref:refs/heads/master"
]
}
[16:31:05.942] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606494664085589",
"id": "8862461967070435696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f5c636332ad0eb78e26394521ad124b0c7004ed\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606494665563546",
"tags": [
"build_address:luci.chromium.ci/win-asan/15888",
"builder:win-asan",
"buildset:commit/git/4f5c636332ad0eb78e26394521ad124b0c7004ed",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f5c636332ad0eb78e26394521ad124b0c7004ed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027892029750334816",
"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/8862461967070435696/+/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": "1606494665563701",
"url": "https://ci.chromium.org/b/8862461967070435696",
"utcnow_ts": "1606494665916792"
}
}
[16:31:05.942] Task URL: https://ci.chromium.org/b/8862461967070435696
[16:31:05.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:2:0) after 1m0s
[16:31:09.798] Received PubSub notification, asking Buildbucket for the build status
[16:31:09.902] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:06.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:2:0)
[16:32:06.015] Timer tick, asking Buildbucket for the build status
[16:32:06.319] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:06.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:4:0) after 1m0s
[16:33:06.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:4:0)
[16:33:06.372] Timer tick, asking Buildbucket for the build status
[16:33:06.581] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:06.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:5:0) after 1m0s
[16:34:06.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:5:0)
[16:34:06.791] Timer tick, asking Buildbucket for the build status
[16:34:08.286] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:08.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:6:0) after 1m0s
[16:35:08.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:6:0)
[16:35:08.383] Timer tick, asking Buildbucket for the build status
[16:35:08.742] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:08.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:7:0) after 1m0s
[16:36:08.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:7:0)
[16:36:08.906] Timer tick, asking Buildbucket for the build status
[16:36:09.260] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:09.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:8:0) after 1m0s
[16:37:09.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:8:0)
[16:37:09.311] Timer tick, asking Buildbucket for the build status
[16:37:09.384] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:09.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:9:0) after 1m0s
[16:38:09.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:9:0)
[16:38:09.459] Timer tick, asking Buildbucket for the build status
[16:38:09.650] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:09.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:10:0) after 1m0s
[16:39:09.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:10:0)
[16:39:09.749] Timer tick, asking Buildbucket for the build status
[16:39:09.851] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:09.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:11:0) after 1m0s
[16:40:10.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:11:0)
[16:40:10.016] Timer tick, asking Buildbucket for the build status
[16:40:10.426] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:10.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:12:0) after 1m0s
[16:41:10.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:12:0)
[16:41:10.400] Timer tick, asking Buildbucket for the build status
[16:41:10.521] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:10.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:13:0) after 1m0s
[16:42:10.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:13:0)
[16:42:10.578] Timer tick, asking Buildbucket for the build status
[16:42:10.790] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:10.790] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:14:0) after 1m0s
[16:43:10.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:14:0)
[16:43:10.914] Timer tick, asking Buildbucket for the build status
[16:43:11.374] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:11.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:15:0) after 1m0s
[16:44:11.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:15:0)
[16:44:11.463] Timer tick, asking Buildbucket for the build status
[16:44:11.512] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:11.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:16:0) after 1m0s
[16:45:11.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:16:0)
[16:45:11.758] Timer tick, asking Buildbucket for the build status
[16:45:11.863] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:11.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:17:0) after 1m0s
[16:46:11.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:17:0)
[16:46:11.967] Timer tick, asking Buildbucket for the build status
[16:46:12.095] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:12.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:18:0) after 1m0s
[16:47:12.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:18:0)
[16:47:12.254] Timer tick, asking Buildbucket for the build status
[16:47:12.507] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:12.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:19:0) after 1m0s
[16:48:12.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:19:0)
[16:48:12.873] Timer tick, asking Buildbucket for the build status
[16:48:13.456] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:13.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:20:0) after 1m0s
[16:49:13.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:20:0)
[16:49:13.620] Timer tick, asking Buildbucket for the build status
[16:49:13.702] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:13.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:21:0) after 1m0s
[16:50:13.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:21:0)
[16:50:13.936] Timer tick, asking Buildbucket for the build status
[16:50:14.052] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:14.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:22:0) after 1m0s
[16:51:14.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:22:0)
[16:51:14.226] Timer tick, asking Buildbucket for the build status
[16:51:14.450] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:14.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:23:0) after 1m0s
[16:52:14.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:23:0)
[16:52:14.639] Timer tick, asking Buildbucket for the build status
[16:52:15.342] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:15.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:24:0) after 1m0s
[16:53:15.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:24:0)
[16:53:15.467] Timer tick, asking Buildbucket for the build status
[16:53:15.566] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:15.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:25:0) after 1m0s
[16:54:15.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:25:0)
[16:54:15.618] Timer tick, asking Buildbucket for the build status
[16:54:15.756] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:15.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:26:0) after 1m0s
[16:55:15.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:26:0)
[16:55:15.774] Timer tick, asking Buildbucket for the build status
[16:55:16.645] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:16.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:27:0) after 1m0s
[16:56:16.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:27:0)
[16:56:16.666] Timer tick, asking Buildbucket for the build status
[16:56:16.965] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:16.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:28:0) after 1m0s
[16:57:16.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:28:0)
[16:57:16.964] Timer tick, asking Buildbucket for the build status
[16:57:17.254] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:17.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:29:0) after 1m0s
[16:58:17.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:29:0)
[16:58:17.281] Timer tick, asking Buildbucket for the build status
[16:58:17.704] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:17.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:30:0) after 1m0s
[16:59:17.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:30:0)
[16:59:17.759] Timer tick, asking Buildbucket for the build status
[16:59:17.882] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:17.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:31:0) after 1m0s
[17:00:17.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:31:0)
[17:00:17.926] Timer tick, asking Buildbucket for the build status
[17:00:18.321] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:18.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:32:0) after 1m0s
[17:01:18.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:32:0)
[17:01:18.372] Timer tick, asking Buildbucket for the build status
[17:01:18.515] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:18.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:33:0) after 1m0s
[17:02:18.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:33:0)
[17:02:18.517] Timer tick, asking Buildbucket for the build status
[17:02:18.628] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:18.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:34:0) after 1m0s
[17:03:18.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:34:0)
[17:03:18.689] Timer tick, asking Buildbucket for the build status
[17:03:20.172] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:20.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:35:0) after 1m0s
[17:04:20.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:35:0)
[17:04:20.227] Timer tick, asking Buildbucket for the build status
[17:04:20.371] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:20.371] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:36:0) after 1m0s
[17:05:20.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:36:0)
[17:05:20.519] Timer tick, asking Buildbucket for the build status
[17:05:20.668] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:20.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:37:0) after 1m0s
[17:06:20.694] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:37:0)
[17:06:20.694] Timer tick, asking Buildbucket for the build status
[17:06:20.858] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:20.858] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:38:0) after 1m0s
[17:07:20.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:38:0)
[17:07:20.905] Timer tick, asking Buildbucket for the build status
[17:07:21.124] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:21.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:39:0) after 1m0s
[17:08:21.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:39:0)
[17:08:21.140] Timer tick, asking Buildbucket for the build status
[17:08:21.272] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:21.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:40:0) after 1m0s
[17:09:22.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:40:0)
[17:09:22.210] Timer tick, asking Buildbucket for the build status
[17:09:22.474] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:22.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:41:0) after 1m0s
[17:10:22.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:41:0)
[17:10:22.751] Timer tick, asking Buildbucket for the build status
[17:10:24.015] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:24.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:42:0) after 1m0s
[17:11:24.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:42:0)
[17:11:24.418] Timer tick, asking Buildbucket for the build status
[17:11:24.547] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:24.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:43:0) after 1m0s
[17:12:24.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:43:0)
[17:12:24.668] Timer tick, asking Buildbucket for the build status
[17:12:25.093] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:25.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:44:0) after 1m0s
[17:13:25.316] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:44:0)
[17:13:25.316] Timer tick, asking Buildbucket for the build status
[17:13:26.013] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:26.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:45:0) after 1m0s
[17:14:26.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:45:0)
[17:14:26.074] Timer tick, asking Buildbucket for the build status
[17:14:26.203] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:26.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:46:0) after 1m0s
[17:15:26.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:46:0)
[17:15:26.200] Timer tick, asking Buildbucket for the build status
[17:15:27.663] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:27.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:47:0) after 1m0s
[17:16:27.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:47:0)
[17:16:27.828] Timer tick, asking Buildbucket for the build status
[17:16:27.953] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:27.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:48:0) after 1m0s
[17:17:28.010] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:48:0)
[17:17:28.010] Timer tick, asking Buildbucket for the build status
[17:17:28.215] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:28.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:49:0) after 1m0s
[17:18:28.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:49:0)
[17:18:28.296] Timer tick, asking Buildbucket for the build status
[17:18:28.418] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:28.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:50:0) after 1m0s
[17:19:28.431] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:50:0)
[17:19:28.431] Timer tick, asking Buildbucket for the build status
[17:19:30.471] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:30.471] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:51:0) after 1m0s
[17:20:30.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:51:0)
[17:20:30.636] Timer tick, asking Buildbucket for the build status
[17:20:31.731] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:31.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:52:0) after 1m0s
[17:21:31.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:52:0)
[17:21:31.944] Timer tick, asking Buildbucket for the build status
[17:21:32.079] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:32.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:53:0) after 1m0s
[17:22:32.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:53:0)
[17:22:32.191] Timer tick, asking Buildbucket for the build status
[17:22:32.306] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:32.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:54:0) after 1m0s
[17:23:32.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:54:0)
[17:23:32.354] Timer tick, asking Buildbucket for the build status
[17:23:32.449] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:32.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:55:0) after 1m0s
[17:24:32.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:55:0)
[17:24:32.476] Timer tick, asking Buildbucket for the build status
[17:24:32.588] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:32.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:56:0) after 1m0s
[17:25:32.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:56:0)
[17:25:32.641] Timer tick, asking Buildbucket for the build status
[17:25:32.714] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:32.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:57:0) after 1m0s
[17:26:32.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:57:0)
[17:26:32.790] Timer tick, asking Buildbucket for the build status
[17:26:33.183] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:33.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:58:0) after 1m0s
[17:27:33.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:58:0)
[17:27:33.247] Timer tick, asking Buildbucket for the build status
[17:27:33.341] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:33.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:59:0) after 1m0s
[17:28:33.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:59:0)
[17:28:33.510] Timer tick, asking Buildbucket for the build status
[17:28:33.833] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:33.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:60:0) after 1m0s
[17:29:33.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:60:0)
[17:29:33.989] Timer tick, asking Buildbucket for the build status
[17:29:34.168] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:34.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:61:0) after 1m0s
[17:30:34.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:61:0)
[17:30:34.244] Timer tick, asking Buildbucket for the build status
[17:30:34.460] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:34.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:62:0) after 1m0s
[17:31:34.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:62:0)
[17:31:34.569] Timer tick, asking Buildbucket for the build status
[17:31:35.082] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:35.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:63:0) after 1m0s
[17:32:35.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:63:0)
[17:32:35.197] Timer tick, asking Buildbucket for the build status
[17:32:35.342] Build 8862461967070435696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:35.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027892029750334816:64:0) after 1m0s
[17:33:20.970] Received PubSub notification, asking Buildbucket for the build status
[17:33:21.031] Build 8862461967070435696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:33:21.031] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606498400276701",
"created_by": "project:chromium",
"created_ts": "1606494664085589",
"id": "8862461967070435696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4f5c636332ad0eb78e26394521ad124b0c7004ed\"}}",
"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\": \"4f5c636332ad0eb78e26394521ad124b0c7004ed\", \"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-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15888, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"10e6c1e43b65f24bac71ccd12c9a2a099e390ce8\", \"got_dawn_revision\": \"4814bdbdeae40186a61dfb7494390e64820daa4f\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"4f5c636332ad0eb78e26394521ad124b0c7004ed\", \"got_revision_cp\": \"refs/heads/master@{#831588}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"9eaea2245f5c5133f2e05fcf0ad0062d009ca383\", \"got_v8_revision_cp\": \"refs/heads/8.9.64@{#1}\", \"got_webrtc_revision\": \"cd982137dfd88ca4e5fc595100d4b5c32b4d9e0f\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32711}\", \"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\": \"4f5c636332ad0eb78e26394521ad124b0c7004ed\", \"swarm_hashes_refs/heads/master(at){#831588}_without_patch\": {\"absl_hardening_tests\": \"82aa67fbb5885bc90653b5daa2d0c7c71f6c2dba\", \"accessibility_unittests\": \"8d987121f69c1935554ef9d7f0ff1c58d355d0b0\", \"angle_unittests\": \"ef03905a080e873159451ebbf416979dffd3abc1\", \"app_shell_unittests\": \"8ea92b0b0e9b172427563f2020d4e88624368058\", \"aura_unittests\": \"0ff2a04cdc528d95b7b10ba4b2fd78bb937117bc\", \"base_unittests\": \"29fdeef04fae3d67019b3f78070aadc7eb38c500\", \"base_util_unittests\": \"22dae98eec4f8488849be3196607efa28f5e429e\", \"blink_common_unittests\": \"411a9e1af457a1cdda75fe9470f8db4eeea5d0c7\", \"blink_fuzzer_unittests\": \"11983f48b195f4e21e6ba6a98d01467281e445b1\", \"blink_heap_unittests\": \"26a44d7faa3389921318eb193ff8e79e6d304769\", \"blink_platform_unittests\": \"e088719bc949cf3712e386e3395e955f59fe5091\", \"blink_unittests\": \"767ab5d8149406c555477d7af9f80d84eadb1a80\", \"boringssl_crypto_tests\": \"70879457f1fc7b8ba0f90aabe885b4877537f0c8\", \"boringssl_ssl_tests\": \"74d4cfac1c832b14ffe6dd5695a476b5a4421b09\", \"browser_switcher_bho_unittests\": \"af49d9ab209cf4b3b436608eb48c48acd746b828\", \"browser_tests\": \"77aeaeb8697cb87f3ba0759a3c2fb433c828d81a\", \"capture_unittests\": \"95dab6d49021975cb17049c077e80ef9494f7ed4\", \"cast_unittests\": \"003f1528aefff874e57f642b30fc38afda023c20\", \"cc_unittests\": \"0e7337bcccc5da46a9bcbd8af4debaf91e63efb3\", \"chrome_app_unittests\": \"1c2dfb589c1c1f30f67e040b128c7ab649a7b713\", \"chrome_cleaner_unittests\": \"a98f66b8570ed5bbcf58724b21753d8b6e9078c1\", \"chrome_elf_unittests\": \"39b72f11fb091040ea2ab52306f69370cbeb964c\", \"chromedriver_unittests\": \"a4f9f686424a513de17597239d528af09118bf4d\", \"components_browsertests\": \"732ababf2c9c9fc75d89b95cebe8bdada6823338\", \"components_unittests\": \"9d7553a4f34f469ab2f0a86081fb8ef6a218fa5e\", \"compositor_unittests\": \"2f2a7b08fe9b0a34becf7c63aa8e17a7e929af58\", \"content_browsertests\": \"2d5b4c3fd05c6c24d12260ed7f942ce9e54fe367\", \"content_unittests\": \"6d14c6e76a066a4de4077ebb0ba755afd47310c4\", \"courgette_unittests\": \"73e4c0e4357ffe6f9c0bda3b1aa8053c4a350d1e\", \"crashpad_tests\": \"730d05a025601d65d9be64c43a90dd56b9896ff9\", \"cronet_tests\": \"ddb876f47e0d13807052ceaa72be2f2e2050cf17\", \"cronet_unittests\": \"593663189d056d33927ab2d85601943ed37c3ae6\", \"crypto_unittests\": \"1542c0096b5a673dafa8cdbdde8605ab40d8c7b6\", \"delayloads_unittests\": \"9413dbaa062230b821c00adcbdccf52131b1200d\", \"device_unittests\": \"4421f377afe2d26b51c94ab404da7481d54920bc\", \"display_unittests\": \"effafdecd29582f7e0ebf155092077d8c925b3ae\", \"elevation_service_unittests\": \"c59f31f6c26cd3a89f284c3cf4ab45193bf1256d\", \"events_unittests\": \"77707527ff075bdd3c1591c8e43a0257bd47b8bc\", \"extensions_browsertests\": \"9ea87a7692732c3c63f0cdcf7b84d98d31b9d711\", \"extensions_unittests\": \"dedda07272858b113cab9c5b85affae8a5d5510d\", \"filesystem_service_unittests\": \"2b9d6174380df17284c3b6097638632de7184cfe\", \"gcm_unit_tests\": \"a292d4d9c9b4bafd27c58525e7b0f91ed841a052\", \"gcp_unittests\": \"af9f682275aba6e5d9a6faa5131d10408dfda632\", \"gfx_unittests\": \"84af6119c143a0ba2d0798d1281fe8ff9ce163ed\", \"gin_unittests\": \"e4e25694d1f9d4664a93a267732602c60e6e7a80\", \"google_apis_unittests\": \"8cd3ba784f2fa2a782b7f76a793a966d30e7ef3e\", \"gpu_unittests\": \"029d244494bc107fb86b7103f5ba278ecd5195a2\", \"gwp_asan_unittests\": \"fb9e8672dd9392410d8f62def0ac7de9cbe49b04\", \"headless_browsertests\": \"040586a1d51b385e34ef09d3d6773b2c56b4aa69\", \"headless_unittests\": \"2e9f18a7bf772ae107a4c897b3de3036cf7e8f72\", \"install_static_unittests\": \"c5eeb68b34873c43bc8bbf57ddd1b19a4bb4960e\", \"installer_util_unittests\": \"7a25460ba08a2136869ddc9962b1dd962213b99e\", \"interactive_ui_tests\": \"1315880aa54bb33dde997366ffadb45e40c36146\", \"ipc_tests\": \"d03d6ab83a72a59c5c25227c60f6ecf71128d17d\", \"jingle_unittests\": \"1817f4e68111d5b85f818291e6fd363cda11794d\", \"latency_unittests\": \"a25b6175671eeebea1cc996d1ed461e2584e29e4\", \"libjingle_xmpp_unittests\": \"0a84aa47aa771588717a81acc4f0a9e5a7746252\", \"liburlpattern_unittests\": \"fd05ee5b31addaf5047a0e010bb9f6a5a3b1f3d0\", \"media_blink_unittests\": \"1c60416f2a5592b3451812faeae045bd295536fb\", \"media_unittests\": \"e2d8b7d02942f8f38d2b7c65e62d737b5727d021\", \"message_center_unittests\": \"9c307dfd259913b6455933b9ce77bf9d6176a313\", \"midi_unittests\": \"426849819e62b467ba935f7b7b40f4fd5a570f51\", \"mojo_core_unittests\": \"5cd7d119197acac3bc5028d8c948ade749d80ab3\", \"mojo_unittests\": \"5b02c4e594097ef64f4c0736b7db359b08741778\", \"nacl_loader_unittests\": \"f8f1d14d80e5120f3b43ae9c6d40777235d96bbc\", \"native_theme_unittests\": \"1c00e6ad5c7c7a784cde0d52f3bb43159fa6fd86\", \"net_unittests\": \"33283739afd3189ec4168a2fe5d0411279585267\", \"notification_helper_unittests\": \"ae69eac6ad7fcec07c22b88538806c058dad8064\", \"pdf_unittests\": \"f752540d58dffa955d331e9680ee77800cc6eaaa\", \"perfetto_unittests\": \"d06caa1938eae2a690f28ac3fa17cd10886697d4\", \"ppapi_unittests\": \"88dedbd7186eabff990ef8185cedd42ea2711355\", \"printing_unittests\": \"c83bbd6461c482b347a5e599c024c9a37351a4d8\", \"remoting_unittests\": \"1cf4a2661df5119460d02804b3881aaafb800be5\", \"sbox_integration_tests\": \"0823695b4ba828c8c3dcf040649143cd5ee72128\", \"sbox_unittests\": \"d25055c36e66b930aa4d72e0df5e1f84b37a68c9\", \"sbox_validation_tests\": \"a00ad8915b9b94bc6871d3347749ea8accaeb5b0\", \"service_manager_unittests\": \"8bc96ca076cae5e9352c444d15638b3c42f34223\", \"services_unittests\": \"d428dea6202ba94432051427f5fecd37aaab7fbc\", \"setup_unittests\": \"040ad07441c5f3e70048c9146d3427fd4d6e1bf0\", \"shell_dialogs_unittests\": \"0cf360feac3d99810c39d2d9c35f3b980632bd86\", \"skia_unittests\": \"9b4d24e3fe33cbc65c901c896b6c332ad8d612a3\", \"snapshot_unittests\": \"a5aac3b0559e2bea7364cddfa39102c3019c7d45\", \"sql_unittests\": \"190498fc256713750ba125c38236710735059915\", \"storage_unittests\": \"6273d185411768e6a219c47b97991eb6e06d534f\", \"sync_integration_tests\": \"a1f73cc8e5ff33fb70d8a364a8d5143cbdfa4b16\", \"traffic_annotation_auditor_unittests\": \"a751bf21a7ac72ffb81ddb3f72ee3a8378f44cba\", \"ui_base_unittests\": \"1a84283dd7b78da0e818e80e25a9871731ffb083\", \"ui_touch_selection_unittests\": \"b91d67913762b1d5c55df447202d873457927a75\", \"unit_tests\": \"ebd8ad02c14baf86bcb2bde8bdd8dbaea38002fd\", \"updater_tests\": \"8bf81fce5280e03a1ce5a9b8604d30ff353a747e\", \"url_unittests\": \"eea1aba62a0a470fe61554f4564fabf9ee541bc3\", \"views_unittests\": \"ed73168951080de48188dc9690bdd6b8b8f9dac7\", \"viz_unittests\": \"2ef86afa81e357c9d466d0c4c938dc41a2f30888\", \"vr_common_unittests\": \"424adaf305bb1020bbb93564fd80e0492c18a7fe\", \"vr_pixeltests\": \"88980823dd8219a0d1a2d73f66c77fdaf4de3cf7\", \"weblayer_browsertests\": \"919d7de1f85409c6c1becbe8d942e33eb2f82a6d\", \"weblayer_unittests\": \"7d6c8c9f1606c6b9360570b9debde11ed01c97cd\", \"wm_unittests\": \"5822b8088b8f04aad7dcbfb05bbf6e34a648a545\", \"wtf_unittests\": \"19be178a865dcd485577e4cab356c678fb159c75\", \"zlib_unittests\": \"827cd81e434ea291b3382deacf14a071d3870896\", \"zucchini_unittests\": \"5fc74bbf6409902855f80c97fb4a1f73fc22a570\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_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-qhsy\"], \"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": "1606494668665236",
"status": "COMPLETED",
"status_changed_ts": "1606498400586884",
"tags": [
"build_address:luci.chromium.ci/win-asan/15888",
"builder:win-asan",
"buildset:commit/git/4f5c636332ad0eb78e26394521ad124b0c7004ed",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4f5c636332ad0eb78e26394521ad124b0c7004ed",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027892029750334816",
"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/8862461967070435696/+/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:50235cc4ce333b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1606498400591493",
"url": "https://ci.chromium.org/b/8862461967070435696",
"utcnow_ts": "1606498401023745"
}
}
[17:33:21.031] Invocation finished in 1h2m18.885113192s with status SUCCEEDED