[18:05:45.188] New invocation is queued and will start shortly
[18:05:46.920] Starting the invocation (attempt 1)
[18:05:46.965] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:05:46.965] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:05:46.965] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021544283016947024",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e5890aa1540852349cafa9447d957dc7451195a3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021544283016947024",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e5890aa1540852349cafa9447d957dc7451195a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e5890aa1540852349cafa9447d957dc7451195a3",
"gitiles_ref:refs/heads/master"
]
}
[18:05:47.871] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612548347195334",
"id": "8856114220250113920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e5890aa1540852349cafa9447d957dc7451195a3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612548347737828",
"tags": [
"build_address:luci.chromium.ci/win-asan/17184",
"builder:win-asan",
"buildset:commit/git/e5890aa1540852349cafa9447d957dc7451195a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e5890aa1540852349cafa9447d957dc7451195a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021544283016947024",
"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/8856114220250113920/+/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": "1612548347737944",
"url": "https://ci.chromium.org/b/8856114220250113920",
"utcnow_ts": "1612548347862590"
}
}
[18:05:47.871] Task URL: https://ci.chromium.org/b/8856114220250113920
[18:05:47.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:2:0) after 1m0s
[18:05:49.888] Received PubSub notification, asking Buildbucket for the build status
[18:05:49.989] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:47.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:2:0)
[18:06:47.968] Timer tick, asking Buildbucket for the build status
[18:06:48.053] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:48.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:4:0) after 1m0s
[18:07:48.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:4:0)
[18:07:48.092] Timer tick, asking Buildbucket for the build status
[18:07:48.180] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:48.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:5:0) after 1m0s
[18:08:48.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:5:0)
[18:08:48.193] Timer tick, asking Buildbucket for the build status
[18:08:48.396] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:48.397] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:6:0) after 1m0s
[18:09:48.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:6:0)
[18:09:48.455] Timer tick, asking Buildbucket for the build status
[18:09:48.636] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:48.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:7:0) after 1m0s
[18:10:48.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:7:0)
[18:10:48.753] Timer tick, asking Buildbucket for the build status
[18:10:49.317] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:49.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:8:0) after 1m0s
[18:11:49.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:8:0)
[18:11:49.358] Timer tick, asking Buildbucket for the build status
[18:11:49.421] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:49.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:9:0) after 1m0s
[18:12:49.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:9:0)
[18:12:49.496] Timer tick, asking Buildbucket for the build status
[18:12:49.583] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:49.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:10:0) after 1m0s
[18:13:49.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:10:0)
[18:13:49.659] Timer tick, asking Buildbucket for the build status
[18:13:49.721] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:49.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:11:0) after 1m0s
[18:14:49.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:11:0)
[18:14:49.761] Timer tick, asking Buildbucket for the build status
[18:14:49.831] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:49.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:12:0) after 1m0s
[18:15:49.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:12:0)
[18:15:49.937] Timer tick, asking Buildbucket for the build status
[18:15:50.813] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:50.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:13:0) after 1m0s
[18:16:50.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:13:0)
[18:16:50.879] Timer tick, asking Buildbucket for the build status
[18:16:51.252] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:51.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:14:0) after 1m0s
[18:17:51.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:14:0)
[18:17:51.393] Timer tick, asking Buildbucket for the build status
[18:17:51.603] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:51.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:15:0) after 1m0s
[18:18:51.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:15:0)
[18:18:51.639] Timer tick, asking Buildbucket for the build status
[18:18:51.719] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:51.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:16:0) after 1m0s
[18:19:51.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:16:0)
[18:19:51.747] Timer tick, asking Buildbucket for the build status
[18:19:51.808] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:51.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:17:0) after 1m0s
[18:20:51.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:17:0)
[18:20:51.868] Timer tick, asking Buildbucket for the build status
[18:20:51.967] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:51.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:18:0) after 1m0s
[18:21:52.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:18:0)
[18:21:52.018] Timer tick, asking Buildbucket for the build status
[18:21:52.114] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:52.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:19:0) after 1m0s
[18:22:52.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:19:0)
[18:22:52.175] Timer tick, asking Buildbucket for the build status
[18:22:52.421] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:52.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:20:0) after 1m0s
[18:23:52.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:20:0)
[18:23:52.442] Timer tick, asking Buildbucket for the build status
[18:23:52.949] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:52.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:21:0) after 1m0s
[18:24:52.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:21:0)
[18:24:52.971] Timer tick, asking Buildbucket for the build status
[18:24:53.038] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:53.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:22:0) after 1m0s
[18:25:53.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:22:0)
[18:25:53.133] Timer tick, asking Buildbucket for the build status
[18:25:53.261] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:53.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:23:0) after 1m0s
[18:26:53.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:23:0)
[18:26:53.309] Timer tick, asking Buildbucket for the build status
[18:26:53.370] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:53.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:24:0) after 1m0s
[18:27:53.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:24:0)
[18:27:53.457] Timer tick, asking Buildbucket for the build status
[18:27:54.223] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:54.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:25:0) after 1m0s
[18:28:54.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:25:0)
[18:28:54.245] Timer tick, asking Buildbucket for the build status
[18:28:54.377] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:54.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:26:0) after 1m0s
[18:29:54.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:26:0)
[18:29:54.457] Timer tick, asking Buildbucket for the build status
[18:29:54.565] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:54.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:27:0) after 1m0s
[18:30:54.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:27:0)
[18:30:54.591] Timer tick, asking Buildbucket for the build status
[18:30:55.026] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:55.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:28:0) after 1m0s
[18:31:55.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:28:0)
[18:31:55.057] Timer tick, asking Buildbucket for the build status
[18:31:55.454] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:55.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:29:0) after 1m0s
[18:32:55.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:29:0)
[18:32:55.466] Timer tick, asking Buildbucket for the build status
[18:32:55.612] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:32:55.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:30:0) after 1m0s
[18:33:55.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:30:0)
[18:33:55.666] Timer tick, asking Buildbucket for the build status
[18:33:56.426] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:33:56.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:31:0) after 1m0s
[18:34:56.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:31:0)
[18:34:56.477] Timer tick, asking Buildbucket for the build status
[18:34:56.597] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:34:56.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:32:0) after 1m0s
[18:35:56.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:32:0)
[18:35:56.645] Timer tick, asking Buildbucket for the build status
[18:35:56.815] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:35:56.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:33:0) after 1m0s
[18:36:56.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:33:0)
[18:36:56.836] Timer tick, asking Buildbucket for the build status
[18:36:57.098] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:36:57.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:34:0) after 1m0s
[18:37:57.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:34:0)
[18:37:57.144] Timer tick, asking Buildbucket for the build status
[18:37:57.445] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:37:57.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:35:0) after 1m0s
[18:38:57.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:35:0)
[18:38:57.513] Timer tick, asking Buildbucket for the build status
[18:38:57.636] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:38:57.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:36:0) after 1m0s
[18:39:57.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:36:0)
[18:39:57.643] Timer tick, asking Buildbucket for the build status
[18:39:57.715] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:39:57.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:37:0) after 1m0s
[18:40:57.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:37:0)
[18:40:57.753] Timer tick, asking Buildbucket for the build status
[18:40:57.883] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:40:57.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:38:0) after 1m0s
[18:41:57.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:38:0)
[18:41:57.914] Timer tick, asking Buildbucket for the build status
[18:41:57.975] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:41:57.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:39:0) after 1m0s
[18:42:58.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:39:0)
[18:42:58.004] Timer tick, asking Buildbucket for the build status
[18:42:58.709] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:42:58.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:40:0) after 1m0s
[18:43:58.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:40:0)
[18:43:58.831] Timer tick, asking Buildbucket for the build status
[18:43:58.942] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:58.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:41:0) after 1m0s
[18:44:59.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:41:0)
[18:44:59.042] Timer tick, asking Buildbucket for the build status
[18:44:59.116] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:59.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:42:0) after 1m0s
[18:45:59.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:42:0)
[18:45:59.188] Timer tick, asking Buildbucket for the build status
[18:45:59.282] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:59.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:43:0) after 1m0s
[18:46:59.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:43:0)
[18:46:59.324] Timer tick, asking Buildbucket for the build status
[18:46:59.528] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:59.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:44:0) after 1m0s
[18:47:59.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:44:0)
[18:47:59.571] Timer tick, asking Buildbucket for the build status
[18:47:59.633] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:59.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:45:0) after 1m0s
[18:48:59.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:45:0)
[18:48:59.788] Timer tick, asking Buildbucket for the build status
[18:48:59.885] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:59.885] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:46:0) after 1m0s
[18:49:59.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:46:0)
[18:49:59.972] Timer tick, asking Buildbucket for the build status
[18:50:00.242] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:00.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:47:0) after 1m0s
[18:51:01.107] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:47:0)
[18:51:01.107] Timer tick, asking Buildbucket for the build status
[18:51:01.224] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:01.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:48:0) after 1m0s
[18:52:01.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:48:0)
[18:52:01.429] Timer tick, asking Buildbucket for the build status
[18:52:01.550] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:01.550] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:49:0) after 1m0s
[18:53:01.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:49:0)
[18:53:01.967] Timer tick, asking Buildbucket for the build status
[18:53:02.063] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:02.063] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:50:0) after 1m0s
[18:54:02.175] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:50:0)
[18:54:02.175] Timer tick, asking Buildbucket for the build status
[18:54:02.596] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:02.596] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:51:0) after 1m0s
[18:55:02.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:51:0)
[18:55:02.670] Timer tick, asking Buildbucket for the build status
[18:55:02.875] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:02.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:52:0) after 1m0s
[18:56:02.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:52:0)
[18:56:02.923] Timer tick, asking Buildbucket for the build status
[18:56:03.082] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:03.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:53:0) after 1m0s
[18:57:03.209] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:53:0)
[18:57:03.209] Timer tick, asking Buildbucket for the build status
[18:57:03.364] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:03.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:54:0) after 1m0s
[18:58:03.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:54:0)
[18:58:03.383] Timer tick, asking Buildbucket for the build status
[18:58:03.695] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:03.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:55:0) after 1m0s
[18:59:03.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:55:0)
[18:59:03.714] Timer tick, asking Buildbucket for the build status
[18:59:03.782] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:03.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:56:0) after 1m0s
[19:00:03.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:56:0)
[19:00:03.854] Timer tick, asking Buildbucket for the build status
[19:00:04.342] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:04.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:57:0) after 1m0s
[19:01:04.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:57:0)
[19:01:04.449] Timer tick, asking Buildbucket for the build status
[19:01:05.013] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:05.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:58:0) after 1m0s
[19:02:05.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:58:0)
[19:02:05.203] Timer tick, asking Buildbucket for the build status
[19:02:05.312] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:05.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:59:0) after 1m0s
[19:03:05.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:59:0)
[19:03:05.376] Timer tick, asking Buildbucket for the build status
[19:03:06.968] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:06.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:60:0) after 1m0s
[19:04:07.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:60:0)
[19:04:07.027] Timer tick, asking Buildbucket for the build status
[19:04:07.171] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:07.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:61:0) after 1m0s
[19:05:07.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:61:0)
[19:05:07.283] Timer tick, asking Buildbucket for the build status
[19:05:07.401] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:07.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:62:0) after 1m0s
[19:06:07.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:62:0)
[19:06:07.445] Timer tick, asking Buildbucket for the build status
[19:06:07.584] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:07.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:63:0) after 1m0s
[19:07:07.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:63:0)
[19:07:07.638] Timer tick, asking Buildbucket for the build status
[19:07:08.007] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:08.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:64:0) after 1m0s
[19:08:08.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:64:0)
[19:08:08.058] Timer tick, asking Buildbucket for the build status
[19:08:08.221] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:08.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:65:0) after 1m0s
[19:09:08.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:65:0)
[19:09:08.413] Timer tick, asking Buildbucket for the build status
[19:09:08.613] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:08.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:66:0) after 1m0s
[19:10:08.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:66:0)
[19:10:08.966] Timer tick, asking Buildbucket for the build status
[19:10:09.337] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:09.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:67:0) after 1m0s
[19:11:09.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:67:0)
[19:11:09.341] Timer tick, asking Buildbucket for the build status
[19:11:09.458] Build 8856114220250113920: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:09.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021544283016947024:68:0) after 1m0s
[19:11:18.325] Received PubSub notification, asking Buildbucket for the build status
[19:11:18.417] Build 8856114220250113920: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:11:18.417] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612552277468640",
"created_by": "project:chromium",
"created_ts": "1612548347195334",
"id": "8856114220250113920",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e5890aa1540852349cafa9447d957dc7451195a3\"}}",
"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\": \"e5890aa1540852349cafa9447d957dc7451195a3\", \"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-0-be5b\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17184, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"b2de0688d7c95c58b4bf1182f53bf43e42a2d99c\", \"got_dawn_revision\": \"185c6a5b0f358c115e3d3ef43afbb157d4c4b591\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"e5890aa1540852349cafa9447d957dc7451195a3\", \"got_revision_cp\": \"refs/heads/master@{#851198}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a8da847d13385bb1c4322ae3c456e4896976c93b\", \"got_v8_revision\": \"bf60416e680c7eab4f2dd7589563f2145fbfdd0a\", \"got_v8_revision_cp\": \"refs/heads/9.0.136@{#1}\", \"got_webrtc_revision\": \"1184b5537fdc37535cb3d099db6a49a2b759f20b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33176}\", \"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\": \"e5890aa1540852349cafa9447d957dc7451195a3\", \"swarm_hashes_refs/heads/master(at){#851198}_without_patch\": {\"absl_hardening_tests\": \"aad753e467464e4cbb4167d5ddbd33af9a596ffa\", \"accessibility_unittests\": \"0dc54b905101fe102d0bacd4faa015c4b807d80a\", \"angle_unittests\": \"76b2df632a4f43a8846df37bd41a057a06f5c94d\", \"app_shell_unittests\": \"348d45c0daaceb16ac13186963c51c80f5a315bd\", \"aura_unittests\": \"0fe7b1231a317e76047f0692cd4e6ffeefe14775\", \"base_unittests\": \"35f9fbf9026135955f739e6cfc9245edd58ab174\", \"base_util_unittests\": \"be45a71b4475740afbaf157b84cd06177b7fcc56\", \"blink_common_unittests\": \"65aeb95de9a1b58b94ee4a5be26cdccf9a294f98\", \"blink_fuzzer_unittests\": \"9ba7cb9715609d27ba6c43365a8b9486690c13b8\", \"blink_heap_unittests\": \"a45726c6065c55345fea1823b46ca7896b3efdc0\", \"blink_platform_unittests\": \"1929456b8116094a19ac7cd68587414874331ade\", \"blink_unittests\": \"25ee9a71d15d905b02e33fbd6eb018ebcbb3f832\", \"boringssl_crypto_tests\": \"bdd426956f5a015fe891f1400ed1ce85e41a4bea\", \"boringssl_ssl_tests\": \"2d233be7065ce1289d8e61574153ec66681bf93a\", \"browser_switcher_bho_unittests\": \"0fc9d8fa3c47ebf0dc889b68c9403b1411f528d2\", \"browser_tests\": \"ec8144e3dcffd53778849e2ceb727ec0d6510a8b\", \"capture_unittests\": \"29e4761a643ee579f702f02735ce1f5dff49b7e4\", \"cast_unittests\": \"7f5dd909aa60fd6ba721556a26d6ee6c9434fbbe\", \"cc_unittests\": \"11f6b0e9bb6134c4f91be93fc29c2602b352c42f\", \"chrome_app_unittests\": \"69aed217e0649ab04e6fc5b663334c58e28a2feb\", \"chrome_cleaner_unittests\": \"a9f4fc6e6404437d365b72c010132b31d220b54f\", \"chrome_elf_unittests\": \"cb05d7b31d791c7617f5949e10ecb5425dd88110\", \"chromedriver_unittests\": \"0bba2ff7d963f2a1078c46deb21df491ccafbe10\", \"components_browsertests\": \"3b7d0c568ed9c5038d768e35597e1bdf55f6402d\", \"components_unittests\": \"43b647598343a154b9d4177efbaba8df1aaed374\", \"compositor_unittests\": \"6e391ed2e0055ebd1c2c36f220228e643dbc5a28\", \"content_browsertests\": \"30717138be38afb14760c3abff972e3e676612dc\", \"content_unittests\": \"0630d07b88c30beea0bb713bffbfb66ba3685ccd\", \"courgette_unittests\": \"fdd75350931f2f1276a02e94fd3f286a650e6d98\", \"crashpad_tests\": \"c70926bcd5a42c72e42b4ce652d2389212790344\", \"cronet_tests\": \"eca15dcf0015effe25c9d9637b31dc9fd55139eb\", \"cronet_unittests\": \"105d22c66df352394698af0d23281a691c2e9c6f\", \"crypto_unittests\": \"04ec92b616a3f0e9a31a9725e3a08dd6223cb10f\", \"delayloads_unittests\": \"29aa9ce3d8f5af87a1cc6aebbb3186d34b4da9dc\", \"device_unittests\": \"227993d6a079a8b34385f0375c58c1f582d5d33e\", \"display_unittests\": \"37a8e285e2e8db9d3c347cd6547a1b424fbf977b\", \"elevation_service_unittests\": \"09147c0419f5aa2524b6e2da2dc93b763b14f6a8\", \"events_unittests\": \"ee3e6987d1b73aec84d46094db205bc1f27ed210\", \"extensions_browsertests\": \"5e6752137a0a060003debd21121a0e1a5d40583f\", \"extensions_unittests\": \"44ff4526b037be2b4933754124b4127e034055bc\", \"filesystem_service_unittests\": \"caa2cbd62292e39b761c113216fb881258bdaf7c\", \"gcm_unit_tests\": \"524a8b9fc92e0660100a7c0a48a88cd45f962473\", \"gcp_unittests\": \"746b4a7d369acca296b55ae5e146a79074f788aa\", \"gfx_unittests\": \"02ffe2c6ed51579907cea81cd77c6d4c2b637213\", \"gin_unittests\": \"dfbeb9ca21d91de1fd068cfa9eacd38c942cf451\", \"google_apis_unittests\": \"eb75a56f20ea7d377e15b1053612f26d395549f0\", \"gpu_unittests\": \"c1e6c1de95442fed592c3f3b36e2a87060830c23\", \"gwp_asan_unittests\": \"eaf0242c811eafb5b67835314e5afa13ec5afe52\", \"headless_browsertests\": \"8e8de23ef693eab7c52b68c112ca6ec550a57a30\", \"headless_unittests\": \"c760621fa5aafabd0dc74bf499b529b9279ba0a3\", \"install_static_unittests\": \"fdd4fda4d69ade1abfcb0ca9d28d7530d21e52f3\", \"installer_util_unittests\": \"f4f8f4719c0ae2dbb4034aea02d22959201af4aa\", \"interactive_ui_tests\": \"d087771993084d4f8e8f69f90bd1ba131b817c3c\", \"ipc_tests\": \"c252c4c1d46426dd22c7548fabdf3fd4815ddbda\", \"jingle_unittests\": \"3eb8d54ea079dd2e8e06728deb3dd4cfc46c387b\", \"latency_unittests\": \"6812be40fb5ed544d37a25e77889c25003b48d46\", \"libjingle_xmpp_unittests\": \"16399435fadb544efb7ae90309d4a74a2c3f05dd\", \"liburlpattern_unittests\": \"a88e6ed4555c7ae40da9dc8c3e222b46dcb3ea4a\", \"media_blink_unittests\": \"d28d1d0a619d6a5561c5a5f12d194d0e9a0e3b00\", \"media_unittests\": \"0f68a837fe8804f8cf3f87cf7e6a90f2c6c91cf8\", \"message_center_unittests\": \"05bf346e8902e716c73c33a770ac3f818d6789b3\", \"midi_unittests\": \"febf39f4db2541f87b41d2679db86105f8c39d60\", \"mojo_core_unittests\": \"51884abcca801d2248eb7d3a047f9773e1410020\", \"mojo_unittests\": \"d79852ffe956a22eaa52b89a25579151380edecb\", \"nacl_loader_unittests\": \"ba0d71c4219e90692784cd719d5fe3f32e9d2f0d\", \"native_theme_unittests\": \"5bec7e1e972692556e09977fbb36233daffd982b\", \"net_unittests\": \"65b007d4f8548547e2cc7931863a9c233db20dc1\", \"notification_helper_unittests\": \"77bd19c0cdcd949707b26f32bab44c9916412d24\", \"pdf_unittests\": \"fff5955f742332d7b3df4ba613f1c8b1f418df0e\", \"perfetto_unittests\": \"06c3fbeb4e5d8f2b4bf6c80880a9b400c0080166\", \"ppapi_unittests\": \"fd0eb306d3e2de4c3be21f9177c15d9a594ae30f\", \"printing_unittests\": \"a5994b945c9d233a09c7fc7b97842482e3fb188f\", \"remoting_unittests\": \"75a1d51e46f13c328cbcfdbcf9e4a0d10bccb36e\", \"sbox_integration_tests\": \"b0bb3278f2272757af1ece83dfddf30773b9a8cf\", \"sbox_unittests\": \"f1f1183c523473235d0c5da3f8188c9c08fac18b\", \"sbox_validation_tests\": \"201f4a10b1b20806fb991800f797aa9ef591d13d\", \"service_manager_unittests\": \"21b78441bdf04f7d3d109cc95c209cd933d88959\", \"services_unittests\": \"a06e59aeaba8bea5dc333933eb0246eaf76956a3\", \"setup_unittests\": \"eb2fbe4cab7a7a42422f535dc8e60d6a66365d15\", \"shell_dialogs_unittests\": \"1e193724eab5a23056862ee304c01cae44fd8da5\", \"skia_unittests\": \"6d13c8851c89d34ede5c5ad091adf947b23b0151\", \"snapshot_unittests\": \"ed08ecb454e4c1f1bb728642bd9fef754b4d3869\", \"sql_unittests\": \"d884bda823ebcc99aa5acaf790ce41a08d3db23d\", \"storage_unittests\": \"08bd665d91eb19f0e81c0d1a6891a22d78d077cc\", \"sync_integration_tests\": \"0f9d9886fa9ec24765a11ee29a9e5a77b78af595\", \"traffic_annotation_auditor_unittests\": \"d19d7ddb62815eba0f74e34ad15681e321266ee5\", \"ui_base_unittests\": \"fa8ec41fc4a118b66548d23057fecf5bf5b8d4b2\", \"ui_touch_selection_unittests\": \"1b5e5d912c601b65ad9d4fac0fd04ecdb26b1e12\", \"unit_tests\": \"d81ce319f87463aec8754ab961f0fb8d97a69a26\", \"updater_tests\": \"54f6ca6e65618435fb6b1c140a04a43e0627394a\", \"url_unittests\": \"3a75e2c3b2dd6b5b6e6e466dbc9791200148d5f0\", \"views_unittests\": \"ad2b8d1ed9d95c5b46972e5dc7db60942372a4dc\", \"viz_unittests\": \"a03ecc61e5a081d26343bdca87ebe9be479e05b4\", \"vr_common_unittests\": \"b956580b075eb621fc9abf186e5d25f6022d3daa\", \"vr_pixeltests\": \"d87bcd0f019f736b7d91243e39ad2b9dddac437f\", \"weblayer_browsertests\": \"d0845e1d71122b8cb2cbe5420e83342515660f8f\", \"weblayer_unittests\": \"61195567151811fd59484b7c681f4c79b38d0be8\", \"wm_unittests\": \"051e9469d7166d851f283e9647994f7185bd0ff0\", \"wtf_unittests\": \"865e3cfaa4269f0cf21bc3c6a4e570738a9df775\", \"zlib_unittests\": \"242ad0aa555691551c39db1126490d32dd206b37\", \"zucchini_unittests\": \"1606c349feea14415d9593a8b780447577ef01f2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-0-be5b\"], \"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\": [\"5676-c76598c\"], \"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": "1612548348771787",
"status": "COMPLETED",
"status_changed_ts": "1612552277972948",
"tags": [
"build_address:luci.chromium.ci/win-asan/17184",
"builder:win-asan",
"buildset:commit/git/e5890aa1540852349cafa9447d957dc7451195a3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e5890aa1540852349cafa9447d957dc7451195a3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021544283016947024",
"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/8856114220250113920/+/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:518c30a08aa2e210",
"user_agent:luci-scheduler"
],
"updated_ts": "1612552277977032",
"url": "https://ci.chromium.org/b/8856114220250113920",
"utcnow_ts": "1612552278407926"
}
}
[19:11:18.417] Invocation finished in 1h5m33.243491814s with status SUCCEEDED