[07:04:12.259] New invocation is queued and will start shortly
[07:04:13.348] Starting the invocation (attempt 1)
[07:04:13.400] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:04:13.402] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:04:13.402] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023669634300020672",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"338670cb44aae389179d67dcfcf57a815b5139e0\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023669634300020672",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/338670cb44aae389179d67dcfcf57a815b5139e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/338670cb44aae389179d67dcfcf57a815b5139e0",
"gitiles_ref:refs/heads/master"
]
}
[07:04:15.224] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610521453550250",
"id": "8858239572280183280",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"338670cb44aae389179d67dcfcf57a815b5139e0\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610521454897519",
"tags": [
"build_address:luci.chromium.ci/win-asan/16792",
"builder:win-asan",
"buildset:commit/git/338670cb44aae389179d67dcfcf57a815b5139e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/338670cb44aae389179d67dcfcf57a815b5139e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023669634300020672",
"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/8858239572280183280/+/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": "1610521454897676",
"url": "https://ci.chromium.org/b/8858239572280183280",
"utcnow_ts": "1610521455205722"
}
}
[07:04:15.224] Task URL: https://ci.chromium.org/b/8858239572280183280
[07:04:15.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:2:0) after 1m0s
[07:04:19.606] Received PubSub notification, asking Buildbucket for the build status
[07:04:19.830] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:15.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:2:0)
[07:05:15.375] Timer tick, asking Buildbucket for the build status
[07:05:15.448] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:05:15.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:4:0) after 1m0s
[07:06:15.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:4:0)
[07:06:15.506] Timer tick, asking Buildbucket for the build status
[07:06:15.609] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:06:15.609] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:5:0) after 1m0s
[07:07:15.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:5:0)
[07:07:15.583] Timer tick, asking Buildbucket for the build status
[07:07:15.715] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:07:15.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:6:0) after 1m0s
[07:08:15.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:6:0)
[07:08:15.805] Timer tick, asking Buildbucket for the build status
[07:08:16.129] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:08:16.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:7:0) after 1m0s
[07:09:16.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:7:0)
[07:09:16.110] Timer tick, asking Buildbucket for the build status
[07:09:16.257] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:09:16.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:8:0) after 1m0s
[07:10:16.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:8:0)
[07:10:16.402] Timer tick, asking Buildbucket for the build status
[07:10:16.634] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:10:16.634] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:9:0) after 1m0s
[07:11:16.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:9:0)
[07:11:16.683] Timer tick, asking Buildbucket for the build status
[07:11:16.771] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:11:16.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:10:0) after 1m0s
[07:12:16.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:10:0)
[07:12:16.900] Timer tick, asking Buildbucket for the build status
[07:12:16.967] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:12:16.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:11:0) after 1m0s
[07:13:17.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:11:0)
[07:13:17.138] Timer tick, asking Buildbucket for the build status
[07:13:17.227] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:13:17.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:12:0) after 1m0s
[07:14:17.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:12:0)
[07:14:17.359] Timer tick, asking Buildbucket for the build status
[07:14:17.597] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:14:17.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:13:0) after 1m0s
[07:15:17.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:13:0)
[07:15:17.622] Timer tick, asking Buildbucket for the build status
[07:15:17.850] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:15:17.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:14:0) after 1m0s
[07:16:17.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:14:0)
[07:16:17.909] Timer tick, asking Buildbucket for the build status
[07:16:18.037] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:16:18.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:15:0) after 1m0s
[07:17:18.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:15:0)
[07:17:18.075] Timer tick, asking Buildbucket for the build status
[07:17:18.209] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:17:18.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:16:0) after 1m0s
[07:18:18.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:16:0)
[07:18:18.258] Timer tick, asking Buildbucket for the build status
[07:18:18.364] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:18:18.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:17:0) after 1m0s
[07:19:18.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:17:0)
[07:19:18.440] Timer tick, asking Buildbucket for the build status
[07:19:18.597] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:19:18.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:18:0) after 1m0s
[07:20:18.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:18:0)
[07:20:18.631] Timer tick, asking Buildbucket for the build status
[07:20:18.809] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:20:18.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:19:0) after 1m0s
[07:21:18.827] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:19:0)
[07:21:18.827] Timer tick, asking Buildbucket for the build status
[07:21:18.878] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:21:18.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:20:0) after 1m0s
[07:22:18.921] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:20:0)
[07:22:18.921] Timer tick, asking Buildbucket for the build status
[07:22:19.028] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:22:19.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:21:0) after 1m0s
[07:23:19.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:21:0)
[07:23:19.053] Timer tick, asking Buildbucket for the build status
[07:23:19.119] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:23:19.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:22:0) after 1m0s
[07:24:19.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:22:0)
[07:24:19.143] Timer tick, asking Buildbucket for the build status
[07:24:19.269] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:24:19.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:23:0) after 1m0s
[07:25:19.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:23:0)
[07:25:19.265] Timer tick, asking Buildbucket for the build status
[07:25:19.393] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:25:19.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:24:0) after 1m0s
[07:26:19.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:24:0)
[07:26:19.483] Timer tick, asking Buildbucket for the build status
[07:26:19.650] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:26:19.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:25:0) after 1m0s
[07:27:19.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:25:0)
[07:27:19.670] Timer tick, asking Buildbucket for the build status
[07:27:19.938] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:27:19.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:26:0) after 1m0s
[07:28:20.011] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:26:0)
[07:28:20.011] Timer tick, asking Buildbucket for the build status
[07:28:20.168] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:28:20.168] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:27:0) after 1m0s
[07:29:20.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:27:0)
[07:29:20.179] Timer tick, asking Buildbucket for the build status
[07:29:20.274] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:29:20.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:28:0) after 1m0s
[07:30:20.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:28:0)
[07:30:20.301] Timer tick, asking Buildbucket for the build status
[07:30:20.430] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:30:20.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:29:0) after 1m0s
[07:31:20.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:29:0)
[07:31:20.433] Timer tick, asking Buildbucket for the build status
[07:31:20.621] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:31:20.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:30:0) after 1m0s
[07:32:20.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:30:0)
[07:32:20.650] Timer tick, asking Buildbucket for the build status
[07:32:20.757] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:32:20.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:31:0) after 1m0s
[07:33:20.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:31:0)
[07:33:20.782] Timer tick, asking Buildbucket for the build status
[07:33:21.003] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:33:21.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:32:0) after 1m0s
[07:34:21.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:32:0)
[07:34:21.064] Timer tick, asking Buildbucket for the build status
[07:34:21.185] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:34:21.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:33:0) after 1m0s
[07:35:21.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:33:0)
[07:35:21.213] Timer tick, asking Buildbucket for the build status
[07:35:21.312] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:35:21.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:34:0) after 1m0s
[07:36:21.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:34:0)
[07:36:21.332] Timer tick, asking Buildbucket for the build status
[07:36:21.562] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:21.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:35:0) after 1m0s
[07:37:21.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:35:0)
[07:37:21.634] Timer tick, asking Buildbucket for the build status
[07:37:21.783] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:21.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:36:0) after 1m0s
[07:38:21.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:36:0)
[07:38:21.813] Timer tick, asking Buildbucket for the build status
[07:38:21.981] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:21.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:37:0) after 1m0s
[07:39:22.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:37:0)
[07:39:22.077] Timer tick, asking Buildbucket for the build status
[07:39:22.213] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:22.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:38:0) after 1m0s
[07:40:22.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:38:0)
[07:40:22.239] Timer tick, asking Buildbucket for the build status
[07:40:22.303] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:22.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:39:0) after 1m0s
[07:41:22.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:39:0)
[07:41:22.351] Timer tick, asking Buildbucket for the build status
[07:41:22.449] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:22.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:40:0) after 1m0s
[07:42:22.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:40:0)
[07:42:22.507] Timer tick, asking Buildbucket for the build status
[07:42:22.715] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:22.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:41:0) after 1m0s
[07:43:22.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:41:0)
[07:43:22.728] Timer tick, asking Buildbucket for the build status
[07:43:22.807] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:22.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:42:0) after 1m0s
[07:44:22.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:42:0)
[07:44:22.829] Timer tick, asking Buildbucket for the build status
[07:44:22.991] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:22.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:43:0) after 1m0s
[07:45:22.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:43:0)
[07:45:22.986] Timer tick, asking Buildbucket for the build status
[07:45:23.129] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:23.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:44:0) after 1m0s
[07:46:23.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:44:0)
[07:46:23.162] Timer tick, asking Buildbucket for the build status
[07:46:23.259] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:23.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:45:0) after 1m0s
[07:47:23.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:45:0)
[07:47:23.248] Timer tick, asking Buildbucket for the build status
[07:47:23.372] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:23.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:46:0) after 1m0s
[07:48:23.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:46:0)
[07:48:23.507] Timer tick, asking Buildbucket for the build status
[07:48:23.773] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:23.773] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:47:0) after 1m0s
[07:49:23.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:47:0)
[07:49:23.826] Timer tick, asking Buildbucket for the build status
[07:49:23.959] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:23.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:48:0) after 1m0s
[07:50:23.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:48:0)
[07:50:23.984] Timer tick, asking Buildbucket for the build status
[07:50:24.195] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:24.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:49:0) after 1m0s
[07:51:24.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:49:0)
[07:51:24.326] Timer tick, asking Buildbucket for the build status
[07:51:24.403] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:24.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:50:0) after 1m0s
[07:52:24.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:50:0)
[07:52:24.473] Timer tick, asking Buildbucket for the build status
[07:52:24.552] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:24.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:51:0) after 1m0s
[07:53:24.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:51:0)
[07:53:24.700] Timer tick, asking Buildbucket for the build status
[07:53:24.801] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:24.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:52:0) after 1m0s
[07:54:24.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:52:0)
[07:54:24.878] Timer tick, asking Buildbucket for the build status
[07:54:24.981] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:24.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:53:0) after 1m0s
[07:55:25.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:53:0)
[07:55:25.031] Timer tick, asking Buildbucket for the build status
[07:55:25.236] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:25.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:54:0) after 1m0s
[07:56:25.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:54:0)
[07:56:25.244] Timer tick, asking Buildbucket for the build status
[07:56:25.381] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:25.381] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:55:0) after 1m0s
[07:57:25.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:55:0)
[07:57:25.410] Timer tick, asking Buildbucket for the build status
[07:57:25.761] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:25.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:56:0) after 1m0s
[07:58:25.797] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:56:0)
[07:58:25.797] Timer tick, asking Buildbucket for the build status
[07:58:25.889] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:25.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:57:0) after 1m0s
[07:59:25.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:57:0)
[07:59:25.919] Timer tick, asking Buildbucket for the build status
[07:59:26.209] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:26.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:58:0) after 1m0s
[08:00:26.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:58:0)
[08:00:26.345] Timer tick, asking Buildbucket for the build status
[08:00:26.961] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:26.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:59:0) after 1m0s
[08:01:27.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:59:0)
[08:01:27.583] Timer tick, asking Buildbucket for the build status
[08:01:27.714] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:27.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:60:0) after 1m0s
[08:02:27.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:60:0)
[08:02:27.887] Timer tick, asking Buildbucket for the build status
[08:02:28.066] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:28.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:61:0) after 1m0s
[08:03:28.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:61:0)
[08:03:28.112] Timer tick, asking Buildbucket for the build status
[08:03:28.395] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:28.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:62:0) after 1m0s
[08:04:28.519] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:62:0)
[08:04:28.519] Timer tick, asking Buildbucket for the build status
[08:04:28.645] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:28.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:63:0) after 1m0s
[08:05:28.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:63:0)
[08:05:28.852] Timer tick, asking Buildbucket for the build status
[08:05:29.948] Build 8858239572280183280: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:29.948] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023669634300020672:64:0) after 1m0s
[08:05:40.192] Received PubSub notification, asking Buildbucket for the build status
[08:05:40.263] Build 8858239572280183280: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[08:05:40.263] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610525138966179",
"created_by": "project:chromium",
"created_ts": "1610521453550250",
"id": "8858239572280183280",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"338670cb44aae389179d67dcfcf57a815b5139e0\"}}",
"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\": \"338670cb44aae389179d67dcfcf57a815b5139e0\", \"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-8-wwgq\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16792, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"2ed9671a0dddaab9c2582b62d3e479895ee0d333\", \"got_dawn_revision\": \"7faa362ea9c2bffbb8e9cc2e2d9f4f4af2fe4dde\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"338670cb44aae389179d67dcfcf57a815b5139e0\", \"got_revision_cp\": \"refs/heads/master@{#842906}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"3549479dc4ccd92ef3b9e179080dcec1a430b01d\", \"got_v8_revision\": \"1e60bce4522aa7c92b1010988f8815f96c8243b7\", \"got_v8_revision_cp\": \"refs/heads/8.9.257@{#1}\", \"got_webrtc_revision\": \"1c5e63e5451374783aaf0259f5c23d3688a5b2ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32938}\", \"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\": \"338670cb44aae389179d67dcfcf57a815b5139e0\", \"swarm_hashes_refs/heads/master(at){#842906}_without_patch\": {\"absl_hardening_tests\": \"1ef04d65afd764da8e896aeba26aada62e6909f3\", \"accessibility_unittests\": \"b4f7e91e0f7859ab0802a087c66197da06788cb5\", \"angle_unittests\": \"55360ef4f7ede6186ee037170e24cdec374213f5\", \"app_shell_unittests\": \"b998b204585595b51034fdb7de84644cd5899aa4\", \"aura_unittests\": \"7ed02e2429f9583d5845c8a6d9751c08c6d1bced\", \"base_unittests\": \"8f3acf2075892dd91ca8b7178188816e9e24fbbb\", \"base_util_unittests\": \"3737961d0c8907433d49c3c79757dfa42e8e044e\", \"blink_common_unittests\": \"74e4e6e79f18deef821cd7296c0dbedb4da3e4a0\", \"blink_fuzzer_unittests\": \"b63b684cca8579a6113604776cfb95a5050baa4f\", \"blink_heap_unittests\": \"155d64eca06b6715cbdc1c5d8da596017e28ecee\", \"blink_platform_unittests\": \"bd6adab0cc53379e8572bbb9deb6b64f96f7cc15\", \"blink_unittests\": \"8c73603896920b9223e1224e8a9def745bc7efba\", \"boringssl_crypto_tests\": \"5ee30f498c0f5848954b33a14a1e19c67cec788d\", \"boringssl_ssl_tests\": \"ee8da5a346cba968462f7108f0783dfd697f9e1a\", \"browser_switcher_bho_unittests\": \"7b8bc711ffb6bead9b12b397a47bae67cb5f6cb3\", \"browser_tests\": \"0d8785e94803f0a62fd1d9029b5c948d4c2c11f4\", \"capture_unittests\": \"2148c084b4add25855da06ecef16fd5fb477096e\", \"cast_unittests\": \"2abe40b9816e4d5e89ad5999d50345e6b45d122a\", \"cc_unittests\": \"fb04e2dccbc15ce700fc8b60895ef0d7ca83e47c\", \"chrome_app_unittests\": \"3dcf41704455a91655fa9ceee9d294e900a7da9d\", \"chrome_cleaner_unittests\": \"ad8d277e97b9644fcd1e2af60926ee97b6a7296f\", \"chrome_elf_unittests\": \"b045493c5e5a0d7932c6da106539e9b26949771d\", \"chromedriver_unittests\": \"18eefd3cf43135469a5cfb7094f6aec1be6db0e2\", \"components_browsertests\": \"776679b0e5856b9873b1480abe8b007ba471b2c6\", \"components_unittests\": \"73808f2cda964c597ee412f3ab4a907673c9af4f\", \"compositor_unittests\": \"baaed0b80dbf6e4f202147819f937978fc8d6f3d\", \"content_browsertests\": \"55b76b90eab22adcb12d8a012abfa3bbba59d799\", \"content_unittests\": \"8e8ee483f09cd535464fc49d6ad764e3233381a2\", \"courgette_unittests\": \"098be4bf12255bab4f09da15b9478f91f5aa7040\", \"crashpad_tests\": \"361cc0081fa38be3532c6c97962a28b6e246ccea\", \"cronet_tests\": \"ea55d73aa0410d382b0be9c06df23cfa33e7def5\", \"cronet_unittests\": \"4132a5d142d76905ce9f4a42e0bc4692052c9871\", \"crypto_unittests\": \"adb7ab3f67626e0925d8d0be0410ea6582422342\", \"delayloads_unittests\": \"21689301f6aad038eff725fc1b2d6c25d2a88764\", \"device_unittests\": \"22e89f4b7bb199f672fbac3c8fafdfa7ac08210e\", \"display_unittests\": \"b2d374ddd1657a1db9cdc60f14f020da5895b6e0\", \"elevation_service_unittests\": \"4c5fc8255d6e0ffdf2b9a2f55564580b9313ef27\", \"events_unittests\": \"ea9801c2146a87cc8984c3756b36c83a25dd8aea\", \"extensions_browsertests\": \"fd446d7d9271a4510bd6d0b2874cbd8062a46522\", \"extensions_unittests\": \"902394a30297e8d9e279d079b2b6450800535808\", \"filesystem_service_unittests\": \"e520f364def20584f971b93ea66a5b33ec923c37\", \"gcm_unit_tests\": \"9a31e0776c543c90778dac1377c168eafdc3500c\", \"gcp_unittests\": \"e97f10afb00db2446a54855ec042da9181fa314b\", \"gfx_unittests\": \"d4320c4f3be9cd06cac9272664c8f9dadfbc1474\", \"gin_unittests\": \"3915b6d9fbce402fdb0381493218e45b19210b06\", \"google_apis_unittests\": \"4939ef08d29607ea2cf1beca2b79351c3b16c84e\", \"gpu_unittests\": \"9af35690db322811df70af9f46fa3047be130c74\", \"gwp_asan_unittests\": \"15a1f0574f29e720be5b43fa47ee06d9e0434f18\", \"headless_browsertests\": \"70657b5cb15ac14fafca6e50772463fcb07883c5\", \"headless_unittests\": \"b09498b76fb0427fbcbe0a351c4bc59661bb376e\", \"install_static_unittests\": \"665d747ee0c6b437221113486535f95d2f306097\", \"installer_util_unittests\": \"a8d387c2cbfb2ab65e8f5fdf0e783bab23a723d6\", \"interactive_ui_tests\": \"24cf52b54a946b8d4cbd7882c94dd4ca0cc021cc\", \"ipc_tests\": \"e623ee383a4e525ebf2d8d9c6f6225f71841bfa1\", \"jingle_unittests\": \"0c5c2e869c7b6cdc5527b8c0a3fffb02decfc7e1\", \"latency_unittests\": \"79d3dcb34352c4e9df97ff552bd3bbbcc781f8f5\", \"libjingle_xmpp_unittests\": \"93b6af5404d60342d6fafa3f75628cd13d783970\", \"liburlpattern_unittests\": \"62e961b1330c1644b0d3affbb61d654378d011af\", \"media_blink_unittests\": \"0aa3b82643ffd3ab93b9d20846ebef3b1912d8f3\", \"media_unittests\": \"3fb804a8e6bf5c9b42473d2f3c6c29b25eeeb467\", \"message_center_unittests\": \"f60623a448588021d9017ef2e9abdf8200e860d9\", \"midi_unittests\": \"5b17e444f83f7d2aadeacf9f3f870e20c7cf736c\", \"mojo_core_unittests\": \"a00b4b89d31be2ef0e6e41c059f16e5c5d3bc3ac\", \"mojo_unittests\": \"e22301212bd0411ae90f220c511e1c1741eb02a1\", \"nacl_loader_unittests\": \"10cb34437041bf771f9cd6724ce22be717351dce\", \"native_theme_unittests\": \"22506375965dc77a4077e97254412311193cfac0\", \"net_unittests\": \"bbfd42d9d551678c58cf2b497f0ea92856be398a\", \"notification_helper_unittests\": \"cbae50406a32a8e55d5c53fa181ba56729f61d91\", \"pdf_unittests\": \"b2081ed52bc55696e0eb037e3947f439f4119ebe\", \"perfetto_unittests\": \"390f48362389f5bdccdb0cfb1480c57c5f2cab07\", \"ppapi_unittests\": \"71a61cf8ec742ded93d6ab9069afe862baf87bf2\", \"printing_unittests\": \"037a7d712df6bffc419b789e7760c7deb52a06a5\", \"remoting_unittests\": \"21e961043588afbff0f08f1c736c7728aee210f8\", \"sbox_integration_tests\": \"63f3f07972685db3e67474c11c9144490ba3d089\", \"sbox_unittests\": \"013a8f0f93a07a08be956ee47c608e8d190523b1\", \"sbox_validation_tests\": \"80bb28f3ae967686eab469f9cfb08f4c0a6ac8dc\", \"service_manager_unittests\": \"45268e38b386b3a557226d6b4911fe4066786ade\", \"services_unittests\": \"9424ae4c391fc59686ff8d15d68f1c589a3c3e36\", \"setup_unittests\": \"1c73a62c2ca53936d1fd562183a2e6323df34ade\", \"shell_dialogs_unittests\": \"8ff5d7f5c380559e9cae098fd0b666dbef5b47d0\", \"skia_unittests\": \"2f561f3479e5b5f93edcd467894e076b5d6ff0ef\", \"snapshot_unittests\": \"4468ead3ace8b2235359c7a239d540a5f9e92905\", \"sql_unittests\": \"6385b5245ef97c28b769d16b2019b293e544e5ff\", \"storage_unittests\": \"9d1795d2659551e15cf5aed59ad83ce746533cae\", \"sync_integration_tests\": \"980cd45e69f2c67624003e5c4c84b6f5ddd15aa4\", \"traffic_annotation_auditor_unittests\": \"627fa665b562c32cd1eb45d7cb6ce2dd50852826\", \"ui_base_unittests\": \"47e003c8a4260fe2baaeb6f0eefec040ab608673\", \"ui_touch_selection_unittests\": \"eff080004d00ce5edc3e856228ee898d62a4b3ad\", \"unit_tests\": \"ad8e938e069aaec1e985d15825f0a33bb46fb245\", \"updater_tests\": \"20443b5d95d22cdcf1ff8d9f062296a9eab59137\", \"url_unittests\": \"9d1001babf56d179435c7af8624eb9a15bf9a6e1\", \"views_unittests\": \"580385f457619ad1a4df439ca144aba20c0029bb\", \"viz_unittests\": \"82a9169b98790d91a64824351d84d935210aa771\", \"vr_common_unittests\": \"1395a2412fa3660114e89e7ddff4ff604132f2bb\", \"vr_pixeltests\": \"53867078d45a76b514d7229da3d25f359dc025ef\", \"weblayer_browsertests\": \"e607e18f81546598eab8c00861ab8b767f2b351e\", \"weblayer_unittests\": \"a28f7e93ac9e01d3936c289c2c559e9251d09db3\", \"wm_unittests\": \"f7490fad250d376012457148247756e20240638a\", \"wtf_unittests\": \"45e815c1f411e5e6600d061e7349500fadb95d8c\", \"zlib_unittests\": \"eac41890e5ef08112a0c18acda29c480652e0f6d\", \"zucchini_unittests\": \"feb6cc47956e67508e476dd2ec0bd9e27a1b3704\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-8-wwgq\"], \"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\": [\"5661-c6f6250\"], \"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": "1610521458516952",
"status": "COMPLETED",
"status_changed_ts": "1610525139330974",
"tags": [
"build_address:luci.chromium.ci/win-asan/16792",
"builder:win-asan",
"buildset:commit/git/338670cb44aae389179d67dcfcf57a815b5139e0",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/338670cb44aae389179d67dcfcf57a815b5139e0",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023669634300020672",
"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/8858239572280183280/+/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:511360b277146210",
"user_agent:luci-scheduler"
],
"updated_ts": "1610525139336536",
"url": "https://ci.chromium.org/b/8858239572280183280",
"utcnow_ts": "1610525140252764"
}
}
[08:05:40.263] Invocation finished in 1h1m28.022479968s with status SUCCEEDED