[08:16:20.389] New invocation is queued and will start shortly
[08:16:21.617] Starting the invocation (attempt 1)
[08:16:21.652] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:16:21.652] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:16:21.652] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023846289856296672",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e05e939efaefb404911a210173a1f26e223bf7e8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023846289856296672",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e05e939efaefb404911a210173a1f26e223bf7e8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e05e939efaefb404911a210173a1f26e223bf7e8",
"gitiles_ref:refs/heads/master"
]
}
[08:16:22.610] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610352981951470",
"id": "8858416227555917360",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e05e939efaefb404911a210173a1f26e223bf7e8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610352982384790",
"tags": [
"build_address:luci.chromium.ci/win-asan/16751",
"builder:win-asan",
"buildset:commit/git/e05e939efaefb404911a210173a1f26e223bf7e8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e05e939efaefb404911a210173a1f26e223bf7e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023846289856296672",
"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/8858416227555917360/+/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": "1610352982384896",
"url": "https://ci.chromium.org/b/8858416227555917360",
"utcnow_ts": "1610352982602094"
}
}
[08:16:22.610] Task URL: https://ci.chromium.org/b/8858416227555917360
[08:16:22.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:2:0) after 1m0s
[08:17:41.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:2:0)
[08:17:41.606] Timer tick, asking Buildbucket for the build status
[08:17:41.909] Build 8858416227555917360: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[08:17:41.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:3:0) after 1m0s
[08:18:17.495] Received PubSub notification, asking Buildbucket for the build status
[08:18:17.571] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:42.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:3:0)
[08:18:42.611] Timer tick, asking Buildbucket for the build status
[08:18:42.792] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:42.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:5:0) after 1m0s
[08:19:43.021] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:5:0)
[08:19:43.021] Timer tick, asking Buildbucket for the build status
[08:19:43.156] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:43.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:6:0) after 1m0s
[08:20:43.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:6:0)
[08:20:43.246] Timer tick, asking Buildbucket for the build status
[08:20:43.351] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:43.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:7:0) after 1m0s
[08:21:44.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:7:0)
[08:21:44.124] Timer tick, asking Buildbucket for the build status
[08:21:44.236] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:44.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:8:0) after 1m0s
[08:22:44.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:8:0)
[08:22:44.379] Timer tick, asking Buildbucket for the build status
[08:22:44.552] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:44.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:9:0) after 1m0s
[08:23:44.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:9:0)
[08:23:44.621] Timer tick, asking Buildbucket for the build status
[08:23:44.729] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:44.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:10:0) after 1m0s
[08:24:45.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:10:0)
[08:24:45.385] Timer tick, asking Buildbucket for the build status
[08:24:45.454] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:45.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:11:0) after 1m0s
[08:25:45.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:11:0)
[08:25:45.680] Timer tick, asking Buildbucket for the build status
[08:25:45.745] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:45.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:12:0) after 1m0s
[08:26:45.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:12:0)
[08:26:45.917] Timer tick, asking Buildbucket for the build status
[08:26:46.021] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:46.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:13:0) after 1m0s
[08:27:46.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:13:0)
[08:27:46.341] Timer tick, asking Buildbucket for the build status
[08:27:46.447] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:46.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:14:0) after 1m0s
[08:28:46.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:14:0)
[08:28:46.488] Timer tick, asking Buildbucket for the build status
[08:28:46.631] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:46.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:15:0) after 1m0s
[08:29:46.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:15:0)
[08:29:46.643] Timer tick, asking Buildbucket for the build status
[08:29:47.092] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:47.092] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:16:0) after 1m0s
[08:30:47.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:16:0)
[08:30:47.154] Timer tick, asking Buildbucket for the build status
[08:30:47.517] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:47.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:17:0) after 1m0s
[08:31:47.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:17:0)
[08:31:47.599] Timer tick, asking Buildbucket for the build status
[08:31:47.714] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:47.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:18:0) after 1m0s
[08:32:47.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:18:0)
[08:32:47.770] Timer tick, asking Buildbucket for the build status
[08:32:48.199] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:48.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:19:0) after 1m0s
[08:33:48.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:19:0)
[08:33:48.201] Timer tick, asking Buildbucket for the build status
[08:33:48.319] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:48.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:20:0) after 1m0s
[08:34:48.340] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:20:0)
[08:34:48.340] Timer tick, asking Buildbucket for the build status
[08:34:48.473] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:48.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:21:0) after 1m0s
[08:35:48.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:21:0)
[08:35:48.529] Timer tick, asking Buildbucket for the build status
[08:35:48.813] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:48.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:22:0) after 1m0s
[08:36:48.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:22:0)
[08:36:48.865] Timer tick, asking Buildbucket for the build status
[08:36:48.987] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:48.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:23:0) after 1m0s
[08:37:49.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:23:0)
[08:37:49.082] Timer tick, asking Buildbucket for the build status
[08:37:49.693] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:49.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:24:0) after 1m0s
[08:38:49.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:24:0)
[08:38:49.715] Timer tick, asking Buildbucket for the build status
[08:38:49.836] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:49.836] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:25:0) after 1m0s
[08:39:49.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:25:0)
[08:39:49.880] Timer tick, asking Buildbucket for the build status
[08:39:49.985] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:49.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:26:0) after 1m0s
[08:40:50.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:26:0)
[08:40:50.090] Timer tick, asking Buildbucket for the build status
[08:40:50.501] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:50.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:27:0) after 1m0s
[08:41:50.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:27:0)
[08:41:50.593] Timer tick, asking Buildbucket for the build status
[08:41:50.665] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:50.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:28:0) after 1m0s
[08:42:50.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:28:0)
[08:42:50.683] Timer tick, asking Buildbucket for the build status
[08:42:50.807] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:50.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:29:0) after 1m0s
[08:43:50.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:29:0)
[08:43:50.790] Timer tick, asking Buildbucket for the build status
[08:43:50.852] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:50.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:30:0) after 1m0s
[08:44:50.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:30:0)
[08:44:50.905] Timer tick, asking Buildbucket for the build status
[08:44:50.962] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:50.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:31:0) after 1m0s
[08:45:51.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:31:0)
[08:45:51.193] Timer tick, asking Buildbucket for the build status
[08:45:51.333] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:51.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:32:0) after 1m0s
[08:46:51.405] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:32:0)
[08:46:51.405] Timer tick, asking Buildbucket for the build status
[08:46:51.465] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:51.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:33:0) after 1m0s
[08:47:51.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:33:0)
[08:47:51.540] Timer tick, asking Buildbucket for the build status
[08:47:51.704] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:51.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:34:0) after 1m0s
[08:48:51.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:34:0)
[08:48:51.759] Timer tick, asking Buildbucket for the build status
[08:48:51.928] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:51.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:35:0) after 1m0s
[08:49:51.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:35:0)
[08:49:51.982] Timer tick, asking Buildbucket for the build status
[08:49:52.050] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:52.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:36:0) after 1m0s
[08:50:52.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:36:0)
[08:50:52.132] Timer tick, asking Buildbucket for the build status
[08:50:52.203] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:52.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:37:0) after 1m0s
[08:51:52.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:37:0)
[08:51:52.218] Timer tick, asking Buildbucket for the build status
[08:51:52.285] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:52.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:38:0) after 1m0s
[08:52:52.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:38:0)
[08:52:52.302] Timer tick, asking Buildbucket for the build status
[08:52:52.404] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:52.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:39:0) after 1m0s
[08:53:52.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:39:0)
[08:53:52.402] Timer tick, asking Buildbucket for the build status
[08:53:52.500] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:52.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:40:0) after 1m0s
[08:54:52.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:40:0)
[08:54:52.648] Timer tick, asking Buildbucket for the build status
[08:54:52.805] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:52.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:41:0) after 1m0s
[08:55:52.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:41:0)
[08:55:52.802] Timer tick, asking Buildbucket for the build status
[08:55:52.963] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:52.963] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:42:0) after 1m0s
[08:56:53.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:42:0)
[08:56:53.067] Timer tick, asking Buildbucket for the build status
[08:56:53.149] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:53.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:43:0) after 1m0s
[08:57:53.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:43:0)
[08:57:53.216] Timer tick, asking Buildbucket for the build status
[08:57:53.840] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:53.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:44:0) after 1m0s
[08:58:53.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:44:0)
[08:58:53.964] Timer tick, asking Buildbucket for the build status
[08:58:54.075] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:54.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:45:0) after 1m0s
[08:59:54.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:45:0)
[08:59:54.209] Timer tick, asking Buildbucket for the build status
[08:59:54.276] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:54.276] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:46:0) after 1m0s
[09:00:54.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:46:0)
[09:00:54.404] Timer tick, asking Buildbucket for the build status
[09:00:54.481] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:54.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:47:0) after 1m0s
[09:01:54.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:47:0)
[09:01:54.504] Timer tick, asking Buildbucket for the build status
[09:01:54.580] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:54.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:48:0) after 1m0s
[09:02:54.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:48:0)
[09:02:54.686] Timer tick, asking Buildbucket for the build status
[09:02:55.273] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:55.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:49:0) after 1m0s
[09:03:55.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:49:0)
[09:03:55.251] Timer tick, asking Buildbucket for the build status
[09:03:55.384] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:55.384] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:50:0) after 1m0s
[09:04:55.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:50:0)
[09:04:55.375] Timer tick, asking Buildbucket for the build status
[09:04:55.511] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:55.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:51:0) after 1m0s
[09:05:55.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:51:0)
[09:05:55.534] Timer tick, asking Buildbucket for the build status
[09:05:55.642] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:55.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:52:0) after 1m0s
[09:06:55.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:52:0)
[09:06:55.698] Timer tick, asking Buildbucket for the build status
[09:06:55.809] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:55.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:53:0) after 1m0s
[09:07:55.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:53:0)
[09:07:55.876] Timer tick, asking Buildbucket for the build status
[09:07:56.395] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:56.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:54:0) after 1m0s
[09:08:56.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:54:0)
[09:08:56.444] Timer tick, asking Buildbucket for the build status
[09:08:56.515] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:56.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:55:0) after 1m0s
[09:09:56.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:55:0)
[09:09:56.534] Timer tick, asking Buildbucket for the build status
[09:09:56.851] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:56.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:56:0) after 1m0s
[09:10:56.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:56:0)
[09:10:56.879] Timer tick, asking Buildbucket for the build status
[09:10:57.084] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:57.084] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:57:0) after 1m0s
[09:11:57.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:57:0)
[09:11:57.214] Timer tick, asking Buildbucket for the build status
[09:11:57.335] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:57.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:58:0) after 1m0s
[09:12:57.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:58:0)
[09:12:57.448] Timer tick, asking Buildbucket for the build status
[09:12:57.554] Build 8858416227555917360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:57.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023846289856296672:59:0) after 1m0s
[09:13:38.435] Received PubSub notification, asking Buildbucket for the build status
[09:13:38.879] Build 8858416227555917360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[09:13:38.880] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610356417812122",
"created_by": "project:chromium",
"created_ts": "1610352981951470",
"id": "8858416227555917360",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e05e939efaefb404911a210173a1f26e223bf7e8\"}}",
"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\": \"e05e939efaefb404911a210173a1f26e223bf7e8\", \"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-1-ervs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16751, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"0b90eef09f607336330f318bc8e65fa981634160\", \"got_dawn_revision\": \"3a0405597374c9ae12fb93e33aac35826455a958\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"e05e939efaefb404911a210173a1f26e223bf7e8\", \"got_revision_cp\": \"refs/heads/master@{#841928}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"348ba200aa29d5b3a36d696d48d8f619e055219f\", \"got_v8_revision\": \"5d60a181de0863d8d00ae42ec0ee3521f51b501a\", \"got_v8_revision_cp\": \"refs/heads/8.9.229@{#1}\", \"got_webrtc_revision\": \"952be209246ab9ba702159cb246eac0e232b15ae\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32931}\", \"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\": \"e05e939efaefb404911a210173a1f26e223bf7e8\", \"swarm_hashes_refs/heads/master(at){#841928}_without_patch\": {\"absl_hardening_tests\": \"263a2bb1f83e7e3466c0b288cc1d2659da464fa3\", \"accessibility_unittests\": \"f33e36cc8e0c75690d34c630d38f3755b67f8b0f\", \"angle_unittests\": \"915f84af2f71a9f12f648dfed609c440ebc1b97d\", \"app_shell_unittests\": \"fd2cd3181c2a824fee3169e300bb59cda6a03504\", \"aura_unittests\": \"d518d8a49e7a6041b1217a4a5b99a6419f171b00\", \"base_unittests\": \"28e1bd110f60db17f89395e138cb293d53003d9f\", \"base_util_unittests\": \"cf51485a34fa795fc8724b63d7ab7d89d13eb817\", \"blink_common_unittests\": \"d2f0dbbebca258b691314621e929cb4b55416abe\", \"blink_fuzzer_unittests\": \"837bd504691518094231f6a160482e6d658dae12\", \"blink_heap_unittests\": \"062ef14d91ebc63d6ec7498bb803017fbf99ec9b\", \"blink_platform_unittests\": \"eb8f67bae221f17ca3e2f2c47e04c19289641472\", \"blink_unittests\": \"b7d39361cc991745c907804f5c5a0f58a5b5d3f4\", \"boringssl_crypto_tests\": \"7ce3b570ffdbdf77e800d94b7e4eb4f2fc2e6dce\", \"boringssl_ssl_tests\": \"b7fa7f4d2c184bb8778f54111768d535187d006b\", \"browser_switcher_bho_unittests\": \"13203f7ff96092eaa32a638298cc9cc849de9e64\", \"browser_tests\": \"edca8e5c598582f476c730ef7b31444bf36ec2d4\", \"capture_unittests\": \"6cfc8bf5179454e0aa30608c6424b53f8d5cb431\", \"cast_unittests\": \"0c68476303f9cd3830f095d499623d578d3a169a\", \"cc_unittests\": \"068b67d3f6462352ab92d588e510bc45d1a5fa0b\", \"chrome_app_unittests\": \"596af04095a77d3ec607ea39543dab304fbe1288\", \"chrome_cleaner_unittests\": \"33fb9f7515eabf65196f066405112e70fe959a0b\", \"chrome_elf_unittests\": \"81213e2f1d4ed89a0906b94892c6b3b139a6ae1e\", \"chromedriver_unittests\": \"5d44a394950448863e95348f0a005bff93391522\", \"components_browsertests\": \"eaf7a7cfcca7e419d04d735e51e972b45c276b54\", \"components_unittests\": \"649b5dbfa1743b1d5ecb50be720fb6271b4158b9\", \"compositor_unittests\": \"11f513d143bc3ebe79cc56b31a71e53ef35c7953\", \"content_browsertests\": \"09ac983cc08744efb9ab3fd8aafc8be2a6df7ef1\", \"content_unittests\": \"07d4b69033f9f96eab097d248ef5a77b2a308994\", \"courgette_unittests\": \"4187f2d13b5f861ae1d1b51514badda04ade0ed8\", \"crashpad_tests\": \"d502bcec8b6a598327c5385e6bf6e118c8f779ef\", \"cronet_tests\": \"5cf4621c3e78b79c215c25f6d10919a6f984a2f9\", \"cronet_unittests\": \"88897ef6ba45d74c27022bfb80d00b9fa3a2cc34\", \"crypto_unittests\": \"57026856aad1fbb82ab97a70a6fb43468277bc09\", \"delayloads_unittests\": \"b87d8349a094c85bbfcb916de4e8d71c2449c86b\", \"device_unittests\": \"ce59f24a6b59809a312c7d54d9c0490693adced9\", \"display_unittests\": \"dd7e5dd916b3ef27809f0746165e0592f55716c4\", \"elevation_service_unittests\": \"8c53da70e7de8dc2b8332ead094e125ecea1ad76\", \"events_unittests\": \"599029480b59325c0f6f19b4575f5528506edc6a\", \"extensions_browsertests\": \"8bd0c8107e83de70d30feda28350d4ad970048eb\", \"extensions_unittests\": \"9a73c4ec5e0b888947315d8e13e0aab426d96ea2\", \"filesystem_service_unittests\": \"daf573cda6521b0b81e4be5df69fbfe114e522fe\", \"gcm_unit_tests\": \"64e77f0182af2e3745e85b5d11629a25137b704d\", \"gcp_unittests\": \"c7fd3efa764d01723b847b67002dc85d0fe14373\", \"gfx_unittests\": \"5b721e6b46c8c8e4082b7ca6f7ced00a070f3fc1\", \"gin_unittests\": \"3b7aebbd9e419f002a6b10290f3aa8332baf4c19\", \"google_apis_unittests\": \"65670a296ff9aa662e8ba2ae6e72af0255ced544\", \"gpu_unittests\": \"e6259e2dc1991b2f8a53367bb5b5dedffe7b5c50\", \"gwp_asan_unittests\": \"c532943201f95285ffff105b15098c14aba5a9a0\", \"headless_browsertests\": \"a91d288b6a04b802c840e4ed875e27e070c6e2bd\", \"headless_unittests\": \"fd281b2612a802aabab9ceb53ad7c712a777ca9b\", \"install_static_unittests\": \"0b8a2358543b31381be64a3861e5617ebee4ada7\", \"installer_util_unittests\": \"1594204e54d804519d8d3a448d4e2d6b16a5b463\", \"interactive_ui_tests\": \"ba64e0066a1bc977429baa022bdeef1428efd2d6\", \"ipc_tests\": \"86128bd4c41013058f0c404a746d7b5554cd8129\", \"jingle_unittests\": \"8b063d84a855244d95cf5c164d46aaf8985e6084\", \"latency_unittests\": \"160d897d3c8eb17c21126ef223a699edad90d500\", \"libjingle_xmpp_unittests\": \"951a8696c0ce92c1a9bfffb15adccd6391095199\", \"liburlpattern_unittests\": \"c873b6028142a0327d2a6b995de111159dbf54df\", \"media_blink_unittests\": \"0a232a7e06e640cc6ae83287c05730076001bf17\", \"media_unittests\": \"2d4c004f1658d41df7723e15b0cece32a926d765\", \"message_center_unittests\": \"7aeb3497db5d5cfb0713f530a0b99c6207869511\", \"midi_unittests\": \"0d15bf6b6896fde1502b5071c9b1450bc418e55e\", \"mojo_core_unittests\": \"61fdd7110655a1bd4e8e3a7b4d4a9238db7eb6bc\", \"mojo_unittests\": \"6e45f749a137acca3790cee89addc8e9d5c1997f\", \"nacl_loader_unittests\": \"ff2dea9b2d94ec3da1a81db394d362813921d9aa\", \"native_theme_unittests\": \"2fc380bcfc887940a07369c0a0c73b93d909b739\", \"net_unittests\": \"22d1bfd47a1b93d0fb3a120a133c4485f1380572\", \"notification_helper_unittests\": \"4904a3733ab6e53800936b05269e53e2ef22dfc8\", \"pdf_unittests\": \"cb7fa62de8c9d4b2eaa4847b9a722663e9906e4f\", \"perfetto_unittests\": \"d956693d0cab28655fdd0ccff1cd2d952921a9bb\", \"ppapi_unittests\": \"00e2ec4808fb96a513539ee17a6a3959ea881550\", \"printing_unittests\": \"f5ebba9c14926812e54fad06bf2f62a530f5367a\", \"remoting_unittests\": \"ca99a15d3d66d2fa72e71004ec7f19721b7571ec\", \"sbox_integration_tests\": \"1638ef3ea4e52bbafba3ba62b0e5fc8c708a9cfa\", \"sbox_unittests\": \"327163edbf124562c39110e07862fa88299ac951\", \"sbox_validation_tests\": \"7af29d15a8af9a0045e2da0349b0d275ba013016\", \"service_manager_unittests\": \"7a61a7208a129185a3028b6c68832b69ab06b5fc\", \"services_unittests\": \"2efcbaff80710115be7297686c3e5be9d8eee06e\", \"setup_unittests\": \"47642bd26d53f17f09885b81eeab465412adca58\", \"shell_dialogs_unittests\": \"1357e312d481deb8dcbfbaf4cd2b71d4820c978e\", \"skia_unittests\": \"49c8b39eb161cd8d97e6a6093f4ad8bfb7f1da36\", \"snapshot_unittests\": \"8144abb088c2e1d8aa0baa608d48191e01efaa9e\", \"sql_unittests\": \"b5c57c6691b922b73882152601541fcd9b1a411d\", \"storage_unittests\": \"a24baced341fd172d17ffd51f76d7a034c64dc48\", \"sync_integration_tests\": \"73fb944619c7e774b74cb4cea3351294c9c8b0ea\", \"traffic_annotation_auditor_unittests\": \"517dafc1a8523311d01e117f81a0e62291b70bae\", \"ui_base_unittests\": \"8f5cf75e2a3d9183e5446a3e3618b1d5f40c86bd\", \"ui_touch_selection_unittests\": \"0994866a61cc3e72a8ebed6acabf8fc6b8b15f04\", \"unit_tests\": \"e471623b923d16315a18c27111a63bbd01a6317b\", \"updater_tests\": \"763df631fef9eb73909be09a3d6a5d3e45d79921\", \"url_unittests\": \"7b76050991be0b0e9a42e538d9c72856f69986a8\", \"views_unittests\": \"31200dcdabef43d077ea57840a85104e0e479774\", \"viz_unittests\": \"ffbbbd0c190cb5fbbce931daa934eff4c6ce29d2\", \"vr_common_unittests\": \"ff35518a705469aaae005ceb7e063e95d19ed671\", \"vr_pixeltests\": \"fc202a9d4a8ec85eb4498f45544c83392397bc59\", \"weblayer_browsertests\": \"37be2ce595d16f7d2e4d7463d730d891b604992c\", \"weblayer_unittests\": \"88345e0f73a5c2cd860ae051716dcdd6093f65fb\", \"wm_unittests\": \"b3f1d128735202fe9bc56771bd5c609d8953f8f4\", \"wtf_unittests\": \"f20af0af1b3b7c92cd9a1a10e93c56edf9b7290b\", \"zlib_unittests\": \"830d0834bbb812f50e90c5b7c59d5ad437289cf9\", \"zucchini_unittests\": \"00ebe21a752a5d428de2a77cdb979ead2f61ca14\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-1-ervs\"], \"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\": [\"5657-0417a3f\"], \"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": "1610353096960100",
"status": "COMPLETED",
"status_changed_ts": "1610356418081016",
"tags": [
"build_address:luci.chromium.ci/win-asan/16751",
"builder:win-asan",
"buildset:commit/git/e05e939efaefb404911a210173a1f26e223bf7e8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e05e939efaefb404911a210173a1f26e223bf7e8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023846289856296672",
"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/8858416227555917360/+/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:5109567662473810",
"user_agent:luci-scheduler"
],
"updated_ts": "1610356418085086",
"url": "https://ci.chromium.org/b/8858416227555917360",
"utcnow_ts": "1610356418853614"
}
}
[09:13:38.880] Invocation finished in 57m18.505169386s with status SUCCEEDED