[20:52:13.156] New invocation is queued and will start shortly
[20:52:14.313] Starting the invocation (attempt 1)
[20:52:14.360] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:52:14.360] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:52:14.360] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022983361342561968",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"59d680fc70acc0272e80aa2bf44205f4b306a7fc\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022983361342561968",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/59d680fc70acc0272e80aa2bf44205f4b306a7fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/59d680fc70acc0272e80aa2bf44205f4b306a7fc",
"gitiles_ref:refs/heads/master"
]
}
[20:52:15.797] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611175934486150",
"id": "8857553299278837248",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"59d680fc70acc0272e80aa2bf44205f4b306a7fc\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611175935488693",
"tags": [
"build_address:luci.chromium.ci/win-asan/16917",
"builder:win-asan",
"buildset:commit/git/59d680fc70acc0272e80aa2bf44205f4b306a7fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/59d680fc70acc0272e80aa2bf44205f4b306a7fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022983361342561968",
"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/8857553299278837248/+/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": "1611175935488833",
"url": "https://ci.chromium.org/b/8857553299278837248",
"utcnow_ts": "1611175935781815"
}
}
[20:52:15.797] Task URL: https://ci.chromium.org/b/8857553299278837248
[20:52:15.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:2:0) after 1m0s
[20:52:19.558] Received PubSub notification, asking Buildbucket for the build status
[20:52:19.630] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:15.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:2:0)
[20:53:15.872] Timer tick, asking Buildbucket for the build status
[20:53:16.230] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:53:16.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:4:0) after 1m0s
[20:54:16.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:4:0)
[20:54:16.308] Timer tick, asking Buildbucket for the build status
[20:54:17.197] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:54:17.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:5:0) after 1m0s
[20:55:17.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:5:0)
[20:55:17.245] Timer tick, asking Buildbucket for the build status
[20:55:17.406] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:55:17.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:6:0) after 1m0s
[20:56:17.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:6:0)
[20:56:17.450] Timer tick, asking Buildbucket for the build status
[20:56:17.585] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:56:17.585] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:7:0) after 1m0s
[20:57:17.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:7:0)
[20:57:17.591] Timer tick, asking Buildbucket for the build status
[20:57:17.802] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:57:17.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:8:0) after 1m0s
[20:58:17.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:8:0)
[20:58:17.829] Timer tick, asking Buildbucket for the build status
[20:58:17.900] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:58:17.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:9:0) after 1m0s
[20:59:17.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:9:0)
[20:59:17.925] Timer tick, asking Buildbucket for the build status
[20:59:19.775] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:59:19.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:10:0) after 1m0s
[21:00:19.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:10:0)
[21:00:19.861] Timer tick, asking Buildbucket for the build status
[21:00:19.958] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:00:19.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:11:0) after 1m0s
[21:01:20.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:11:0)
[21:01:20.019] Timer tick, asking Buildbucket for the build status
[21:01:20.113] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:01:20.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:12:0) after 1m0s
[21:02:20.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:12:0)
[21:02:20.101] Timer tick, asking Buildbucket for the build status
[21:02:20.235] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:02:20.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:13:0) after 1m0s
[21:03:20.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:13:0)
[21:03:20.266] Timer tick, asking Buildbucket for the build status
[21:03:20.337] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:03:20.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:14:0) after 1m0s
[21:04:20.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:14:0)
[21:04:20.353] Timer tick, asking Buildbucket for the build status
[21:04:20.496] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:04:20.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:15:0) after 1m0s
[21:05:20.601] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:15:0)
[21:05:20.601] Timer tick, asking Buildbucket for the build status
[21:05:20.668] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:05:20.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:16:0) after 1m0s
[21:06:20.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:16:0)
[21:06:20.726] Timer tick, asking Buildbucket for the build status
[21:06:20.836] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:06:20.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:17:0) after 1m0s
[21:07:21.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:17:0)
[21:07:21.151] Timer tick, asking Buildbucket for the build status
[21:07:21.330] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:07:21.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:18:0) after 1m0s
[21:08:21.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:18:0)
[21:08:21.425] Timer tick, asking Buildbucket for the build status
[21:08:21.530] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:08:21.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:19:0) after 1m0s
[21:09:21.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:19:0)
[21:09:21.725] Timer tick, asking Buildbucket for the build status
[21:09:21.827] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:09:21.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:20:0) after 1m0s
[21:10:21.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:20:0)
[21:10:21.907] Timer tick, asking Buildbucket for the build status
[21:10:22.209] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:10:22.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:21:0) after 1m0s
[21:11:22.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:21:0)
[21:11:22.271] Timer tick, asking Buildbucket for the build status
[21:11:22.795] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:11:22.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:22:0) after 1m0s
[21:12:22.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:22:0)
[21:12:22.877] Timer tick, asking Buildbucket for the build status
[21:12:23.039] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:12:23.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:23:0) after 1m0s
[21:13:23.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:23:0)
[21:13:23.097] Timer tick, asking Buildbucket for the build status
[21:13:23.173] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:13:23.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:24:0) after 1m0s
[21:14:23.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:24:0)
[21:14:23.261] Timer tick, asking Buildbucket for the build status
[21:14:23.377] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:14:23.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:25:0) after 1m0s
[21:15:23.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:25:0)
[21:15:23.445] Timer tick, asking Buildbucket for the build status
[21:15:23.650] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:15:23.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:26:0) after 1m0s
[21:16:23.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:26:0)
[21:16:23.715] Timer tick, asking Buildbucket for the build status
[21:16:23.849] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:16:23.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:27:0) after 1m0s
[21:17:23.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:27:0)
[21:17:23.896] Timer tick, asking Buildbucket for the build status
[21:17:24.085] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:17:24.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:28:0) after 1m0s
[21:18:24.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:28:0)
[21:18:24.186] Timer tick, asking Buildbucket for the build status
[21:18:24.410] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:18:24.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:29:0) after 1m0s
[21:19:24.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:29:0)
[21:19:24.570] Timer tick, asking Buildbucket for the build status
[21:19:24.776] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:19:24.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:30:0) after 1m0s
[21:20:24.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:30:0)
[21:20:24.814] Timer tick, asking Buildbucket for the build status
[21:20:24.956] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:20:24.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:31:0) after 1m0s
[21:21:25.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:31:0)
[21:21:25.058] Timer tick, asking Buildbucket for the build status
[21:21:25.234] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:21:25.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:32:0) after 1m0s
[21:22:25.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:32:0)
[21:22:25.255] Timer tick, asking Buildbucket for the build status
[21:22:25.405] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:22:25.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:33:0) after 1m0s
[21:23:25.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:33:0)
[21:23:25.438] Timer tick, asking Buildbucket for the build status
[21:23:25.562] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:23:25.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:34:0) after 1m0s
[21:24:25.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:34:0)
[21:24:25.639] Timer tick, asking Buildbucket for the build status
[21:24:25.805] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:24:25.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:35:0) after 1m0s
[21:25:26.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:35:0)
[21:25:26.752] Timer tick, asking Buildbucket for the build status
[21:25:26.834] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:25:26.834] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:36:0) after 1m0s
[21:26:26.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:36:0)
[21:26:26.893] Timer tick, asking Buildbucket for the build status
[21:26:27.420] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:26:27.420] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:37:0) after 1m0s
[21:27:27.394] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:37:0)
[21:27:27.394] Timer tick, asking Buildbucket for the build status
[21:27:27.664] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:27:27.664] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:38:0) after 1m0s
[21:28:27.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:38:0)
[21:28:27.692] Timer tick, asking Buildbucket for the build status
[21:28:27.756] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:28:27.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:39:0) after 1m0s
[21:29:27.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:39:0)
[21:29:27.823] Timer tick, asking Buildbucket for the build status
[21:29:27.912] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:27.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:40:0) after 1m0s
[21:30:28.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:40:0)
[21:30:28.002] Timer tick, asking Buildbucket for the build status
[21:30:28.519] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:28.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:41:0) after 1m0s
[21:31:29.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:41:0)
[21:31:29.073] Timer tick, asking Buildbucket for the build status
[21:31:29.521] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:29.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:42:0) after 1m0s
[21:32:29.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:42:0)
[21:32:29.617] Timer tick, asking Buildbucket for the build status
[21:32:29.757] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:29.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:43:0) after 1m0s
[21:33:29.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:43:0)
[21:33:29.779] Timer tick, asking Buildbucket for the build status
[21:33:29.905] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:29.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:44:0) after 1m0s
[21:34:29.945] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:44:0)
[21:34:29.945] Timer tick, asking Buildbucket for the build status
[21:34:30.056] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:30.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:45:0) after 1m0s
[21:35:30.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:45:0)
[21:35:30.124] Timer tick, asking Buildbucket for the build status
[21:35:30.232] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:30.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:46:0) after 1m0s
[21:36:30.254] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:46:0)
[21:36:30.254] Timer tick, asking Buildbucket for the build status
[21:36:30.335] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:30.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:47:0) after 1m0s
[21:37:30.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:47:0)
[21:37:30.411] Timer tick, asking Buildbucket for the build status
[21:37:30.541] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:30.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:48:0) after 1m0s
[21:38:30.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:48:0)
[21:38:30.774] Timer tick, asking Buildbucket for the build status
[21:38:30.849] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:30.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:49:0) after 1m0s
[21:39:30.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:49:0)
[21:39:30.971] Timer tick, asking Buildbucket for the build status
[21:39:31.196] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:31.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:50:0) after 1m0s
[21:40:31.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:50:0)
[21:40:31.244] Timer tick, asking Buildbucket for the build status
[21:40:31.437] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:31.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:51:0) after 1m0s
[21:41:31.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:51:0)
[21:41:31.546] Timer tick, asking Buildbucket for the build status
[21:41:31.633] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:31.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:52:0) after 1m0s
[21:42:31.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:52:0)
[21:42:31.973] Timer tick, asking Buildbucket for the build status
[21:42:32.350] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:32.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:53:0) after 1m0s
[21:43:32.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:53:0)
[21:43:32.599] Timer tick, asking Buildbucket for the build status
[21:43:32.993] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:32.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:54:0) after 1m0s
[21:44:33.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:54:0)
[21:44:33.047] Timer tick, asking Buildbucket for the build status
[21:44:33.156] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:33.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:55:0) after 1m0s
[21:45:33.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:55:0)
[21:45:33.357] Timer tick, asking Buildbucket for the build status
[21:45:33.480] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:33.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:56:0) after 1m0s
[21:46:33.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:56:0)
[21:46:33.598] Timer tick, asking Buildbucket for the build status
[21:46:33.685] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:33.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:57:0) after 1m0s
[21:47:33.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:57:0)
[21:47:33.768] Timer tick, asking Buildbucket for the build status
[21:47:33.888] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:33.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:58:0) after 1m0s
[21:48:34.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:58:0)
[21:48:34.290] Timer tick, asking Buildbucket for the build status
[21:48:34.689] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:34.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:59:0) after 1m0s
[21:49:34.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:59:0)
[21:49:34.708] Timer tick, asking Buildbucket for the build status
[21:49:34.788] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:34.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:60:0) after 1m0s
[21:50:34.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:60:0)
[21:50:34.875] Timer tick, asking Buildbucket for the build status
[21:50:34.951] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:34.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:61:0) after 1m0s
[21:51:35.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:61:0)
[21:51:35.031] Timer tick, asking Buildbucket for the build status
[21:51:35.115] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:35.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:62:0) after 1m0s
[21:52:35.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:62:0)
[21:52:35.251] Timer tick, asking Buildbucket for the build status
[21:52:35.583] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:35.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:63:0) after 1m0s
[21:53:35.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:63:0)
[21:53:35.617] Timer tick, asking Buildbucket for the build status
[21:53:35.691] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:35.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:64:0) after 1m0s
[21:54:35.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:64:0)
[21:54:35.760] Timer tick, asking Buildbucket for the build status
[21:54:35.855] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:35.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:65:0) after 1m0s
[21:55:35.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:65:0)
[21:55:35.976] Timer tick, asking Buildbucket for the build status
[21:55:36.331] Build 8857553299278837248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:36.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022983361342561968:66:0) after 1m0s
[21:56:12.598] Received PubSub notification, asking Buildbucket for the build status
[21:56:12.752] Build 8857553299278837248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[21:56:12.752] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611179771264467",
"created_by": "project:chromium",
"created_ts": "1611175934486150",
"id": "8857553299278837248",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"59d680fc70acc0272e80aa2bf44205f4b306a7fc\"}}",
"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\": \"59d680fc70acc0272e80aa2bf44205f4b306a7fc\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16917, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"2900a0f4cb8219be40997f72c25bdc71cef66038\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"59d680fc70acc0272e80aa2bf44205f4b306a7fc\", \"got_revision_cp\": \"refs/heads/master@{#845314}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"6dcbcead3da45604b84aef9d9496689fef5ff8f5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33041}\", \"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\": \"59d680fc70acc0272e80aa2bf44205f4b306a7fc\", \"swarm_hashes_refs/heads/master(at){#845314}_without_patch\": {\"absl_hardening_tests\": \"3f014dd4de63d7e9a7ca29581ec8f37e7812391b\", \"accessibility_unittests\": \"c59169ce91cb963b16577229d7e80c43e664f4b2\", \"angle_unittests\": \"b799ebf5027b1d38010618cdb99959d6eaa9268e\", \"app_shell_unittests\": \"c0cf12e2d5e8f9c8ed09adc7591dc1e12514ce07\", \"aura_unittests\": \"998e2038c7e24721e4b01f29e0985aa659515ce6\", \"base_unittests\": \"530168024466e04c12fca1e8c2458cbe7493d0e0\", \"base_util_unittests\": \"5feac01c8bcee32e9be61b4d234f5c57ccfd5f14\", \"blink_common_unittests\": \"6ea768d3e92fb0199da1a8e6e4aed2d50fc5269d\", \"blink_fuzzer_unittests\": \"efbc3c379382f9cf6f35c5aa435df0bcaa3f1f2a\", \"blink_heap_unittests\": \"a59194165bb65f49ccf23e0422c34d3cdd99b8de\", \"blink_platform_unittests\": \"8487cc0ea49f6c8e5edad18323d4b2502f6e0641\", \"blink_unittests\": \"17948f7a452f3954204ae27af41a3e36f712343b\", \"boringssl_crypto_tests\": \"5282675f6387eadd66b96136ef4a12cd746074b7\", \"boringssl_ssl_tests\": \"83d7251e3df241388c47fb434a026c33958d9cf4\", \"browser_switcher_bho_unittests\": \"4cb24c4ec8ecf478b004b6831b2284a28e95bc0b\", \"browser_tests\": \"1ba5777ad813ede22799d947992db196848319de\", \"capture_unittests\": \"cbe01d5f170fe191c841bf288beac5747edd914a\", \"cast_unittests\": \"fbb89710002890cdc3d352160ebd21e9548a8077\", \"cc_unittests\": \"ab9757175eebbe3bd5adc18aa39e0f60d273b541\", \"chrome_app_unittests\": \"8eea9d48228be3b9c7a1c4952e72b7f227fd1429\", \"chrome_cleaner_unittests\": \"74b82ec59aff404c48f7e45ccf4f5edd0952aaea\", \"chrome_elf_unittests\": \"0cc0ef6405a71ecc8224335777c1a868220e5b57\", \"chromedriver_unittests\": \"201a642d157af0d6a702b74124fd400d5de44295\", \"components_browsertests\": \"652579f864c3a2863a5fa3b9fda54c43e072a76a\", \"components_unittests\": \"406cbe343cacdece4e80a0f609f72110de663efb\", \"compositor_unittests\": \"6d0eee373e120cc8737aebb452d1d7bc7911b878\", \"content_browsertests\": \"41154b03e036cca71f246e55efd06913a0f86d98\", \"content_unittests\": \"05d09994d3c5a41f6874ae80bddb84245fdde1ef\", \"courgette_unittests\": \"c5b00908647a4357e4222ca8171329e3f64f76b7\", \"crashpad_tests\": \"487bb6a15a562ca0e9a2b95ec465163812ec74b6\", \"cronet_tests\": \"2f6528ef9a467dba7c0b5dc1757139d4918007c8\", \"cronet_unittests\": \"adfe0935ce543caa11e5b691adf878c51e0d9285\", \"crypto_unittests\": \"e46087da67e759acd46f104161063dd549077bd0\", \"delayloads_unittests\": \"be6f495e1d5bb5ba1321ffe1b012ca42f787fa8b\", \"device_unittests\": \"2a59649f357cb0e576c74d476079dccdb7b3b68a\", \"display_unittests\": \"3f7b1c7ad0e21e26c3f1240c1f92657208fdc64d\", \"elevation_service_unittests\": \"09c87fefb7158e17dbe09f286ee396720cec3649\", \"events_unittests\": \"212312839ced78e4f39d27fe5ddb869f4860447c\", \"extensions_browsertests\": \"56849feaceba08523d216de701b69cebf21fa052\", \"extensions_unittests\": \"f615b05eb35424b08a47e941dd874ae3f178361e\", \"filesystem_service_unittests\": \"d6cbffe41e97ba59167acbde9316baeb3774a0c3\", \"gcm_unit_tests\": \"d950a282c687099e53e606f4a0dde6ba7dba0aa5\", \"gcp_unittests\": \"2cbcbaf1d4a81c73b44d46c7e2bea7c2a18d44fd\", \"gfx_unittests\": \"379811ef782b807cfee17fea0abe188e3c6af615\", \"gin_unittests\": \"bb4ce78407c3cf004f2f294100a84f7685342cba\", \"google_apis_unittests\": \"71a91bbae75838733c93e586da17a5a052e014cb\", \"gpu_unittests\": \"e466e77175dbfcbf97444e106f13f8729e38cb46\", \"gwp_asan_unittests\": \"fad6e72c1489819e4a502ca54f5b0e1441b362b6\", \"headless_browsertests\": \"91b2b37df58a5e2f272573e4defd25a8df7788ce\", \"headless_unittests\": \"8ae9189be5910f762f566b4478aa6932fbfbc80c\", \"install_static_unittests\": \"4ead9c9be42be84a6464a752e092f6a9378bf739\", \"installer_util_unittests\": \"a714543b955505c46df819efb2cac90bd5152ca9\", \"interactive_ui_tests\": \"c0b7447990e3ea8a37c3800460ea6d91178d2567\", \"ipc_tests\": \"08f57c08735b6748327c1975db26ff5072c3f182\", \"jingle_unittests\": \"3ff4ddaa900c9fdfcc90f44063f810de1e142fe4\", \"latency_unittests\": \"92d2ea56b7463d6c537992acc570f0f375f871b8\", \"libjingle_xmpp_unittests\": \"0c2209fc21499434cbb4b2dfda1335fe64d47cee\", \"liburlpattern_unittests\": \"03158c6a4b87c58307eab8d953c9a22c715bdd5c\", \"media_blink_unittests\": \"abe896dac1ca07fc9f7b1be09b4ee806ccadd6d9\", \"media_unittests\": \"0e06689706c870637de9301e3001a7fc65d2ee73\", \"message_center_unittests\": \"87a5e1f68f584468036ff2ce78553be6cf3e35e9\", \"midi_unittests\": \"fa184b076c6f8a2eee199d0145580ee466eb7c3d\", \"mojo_core_unittests\": \"935cf046560a69ed21f97cce7068e624a2c1b421\", \"mojo_unittests\": \"5a36ca16d58d4d186fa00cfeffac09f6ba359f29\", \"nacl_loader_unittests\": \"ae438d44d92b1d6b2a060526f6c1ebf3005fa7f0\", \"native_theme_unittests\": \"14d85e8d61b011ca0bee7913ece6dbfe4bb6c83c\", \"net_unittests\": \"d78805b200e28553f310cf2dc5b9b8bd4d27ca4f\", \"notification_helper_unittests\": \"1527d11f91103f9acfb4512b8218c376c70e2f25\", \"pdf_unittests\": \"e5f18d88214de7be3cfdb59e5543b2030316c5c0\", \"perfetto_unittests\": \"2454099c418a7747e4f8c8034b9dbc8906b8de27\", \"ppapi_unittests\": \"2cf822904bb3a70d18a089a6f3b45229e03a1f50\", \"printing_unittests\": \"ac5b57f31ab6dcca0095a4302fb5633821499afe\", \"remoting_unittests\": \"69b965408d816ac6be057d391ea115b2239a9028\", \"sbox_integration_tests\": \"d45ba21e08c9e70f45feb06eeea931dc50d56dc7\", \"sbox_unittests\": \"273c9bcb03b3181898bbc9451f77569dd3dbbbea\", \"sbox_validation_tests\": \"786e95c51bf0519cd4462eb660c474918f18583a\", \"service_manager_unittests\": \"69edc2886fa881573b14a359e4b479069b4588ef\", \"services_unittests\": \"28fd094b201c17a7e7337d11926df1c8528b9cbb\", \"setup_unittests\": \"01bf28550a0e36fa24323859ee487f3b51529aba\", \"shell_dialogs_unittests\": \"1f94a5a18a73ac9202b273376bf93e9f38222d6a\", \"skia_unittests\": \"0d40a6e228bdba3cb3da7d4f2da17fd5cbbfb735\", \"snapshot_unittests\": \"c204f22197247ef1cdf2631b13866a71ac0557ae\", \"sql_unittests\": \"465d2e510cbf2f7be3f5d9fd43dd47a0ca94b1ac\", \"storage_unittests\": \"c0ade30230019c4fabad1f7ba99b5716abd53f9c\", \"sync_integration_tests\": \"377117c70cfccee7c77b2d38fe97fbd5ddf741bd\", \"traffic_annotation_auditor_unittests\": \"84043274b40d333ec5db0325077b29290fdf5ce3\", \"ui_base_unittests\": \"47eb7f0e134b2ffee11d533ed948ac5294aa77c8\", \"ui_touch_selection_unittests\": \"09685c8f1dc49592f5c762687af2f1ec014ee4d2\", \"unit_tests\": \"a49dc4fdb0cc8d1f6a2ca6fef7a5871a77ab1ee4\", \"updater_tests\": \"bd3c4fcb9a01babff3fdabaa4efc0a996e6096de\", \"url_unittests\": \"b6ca0c25ee1539f9ed8d230599f9baf87cab6ee7\", \"views_unittests\": \"a6ff57ee9fba9f3bbe6b5349447ebd388fd413c2\", \"viz_unittests\": \"069864ee1076adb74ea21b0553d5d3130af1aabf\", \"vr_common_unittests\": \"f03f3730c91ad95237462047c5265fb0d288955f\", \"vr_pixeltests\": \"d185aa44dad60e7e7b5aeb1a0913e2deec589b2e\", \"weblayer_browsertests\": \"5d4b1de33cfa7c07678794abf5cda57a30adc3e4\", \"weblayer_unittests\": \"25579c2c8d89b5672d5894f184b79f2cbfc21af5\", \"wm_unittests\": \"8fdd50835154783441634526a0a41938fa0fe1e2\", \"wtf_unittests\": \"3cdecd747da0007e7af5c1c9492f2f427851e5bb\", \"zlib_unittests\": \"e8484b0ce7e0b127838496686d5f68d394d1c969\", \"zucchini_unittests\": \"a3ddfdddea2fcfe37db70f2fc5f57026d56255e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-0-gbsn\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5666-679c9bd\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1611175938763944",
"status": "COMPLETED",
"status_changed_ts": "1611179771681697",
"tags": [
"build_address:luci.chromium.ci/win-asan/16917",
"builder:win-asan",
"buildset:commit/git/59d680fc70acc0272e80aa2bf44205f4b306a7fc",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/59d680fc70acc0272e80aa2bf44205f4b306a7fc",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022983361342561968",
"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/8857553299278837248/+/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:513a6347af6c2510",
"user_agent:luci-scheduler"
],
"updated_ts": "1611179771700737",
"url": "https://ci.chromium.org/b/8857553299278837248",
"utcnow_ts": "1611179772741052"
}
}
[21:56:12.752] Invocation finished in 1h3m59.61728714s with status SUCCEEDED