[02:31:44.553] New invocation is queued and will start shortly
[02:31:45.704] Starting the invocation (attempt 1)
[02:31:45.742] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:31:45.742] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:31:45.742] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019881703519119696",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"3fea7574bf0beaafe6e53fedb422341e114a2194\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019881703519119696",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/3fea7574bf0beaafe6e53fedb422341e114a2194",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fea7574bf0beaafe6e53fedb422341e114a2194",
"gitiles_ref:refs/heads/master"
]
}
[02:31:46.724] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614133905914381",
"id": "8854451641430464304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3fea7574bf0beaafe6e53fedb422341e114a2194\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614133906399573",
"tags": [
"build_address:luci.chromium.ci/win-asan/17500",
"builder:win-asan",
"buildset:commit/git/3fea7574bf0beaafe6e53fedb422341e114a2194",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fea7574bf0beaafe6e53fedb422341e114a2194",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019881703519119696",
"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/8854451641430464304/+/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": "1614133906399724",
"url": "https://ci.chromium.org/b/8854451641430464304",
"utcnow_ts": "1614133906716817"
}
}
[02:31:46.724] Task URL: https://ci.chromium.org/b/8854451641430464304
[02:31:46.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:2:0) after 1m0s
[02:31:58.238] Received PubSub notification, asking Buildbucket for the build status
[02:31:58.335] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:46.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:2:0)
[02:32:46.771] Timer tick, asking Buildbucket for the build status
[02:32:46.850] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:46.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:4:0) after 1m0s
[02:33:46.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:4:0)
[02:33:46.919] Timer tick, asking Buildbucket for the build status
[02:33:47.102] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:47.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:5:0) after 1m0s
[02:34:47.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:5:0)
[02:34:47.143] Timer tick, asking Buildbucket for the build status
[02:34:47.290] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:47.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:6:0) after 1m0s
[02:35:47.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:6:0)
[02:35:47.337] Timer tick, asking Buildbucket for the build status
[02:35:47.511] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:47.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:7:0) after 1m0s
[02:36:47.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:7:0)
[02:36:47.542] Timer tick, asking Buildbucket for the build status
[02:36:47.890] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:47.890] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:8:0) after 1m0s
[02:37:47.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:8:0)
[02:37:47.932] Timer tick, asking Buildbucket for the build status
[02:37:48.128] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:48.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:9:0) after 1m0s
[02:38:48.171] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:9:0)
[02:38:48.171] Timer tick, asking Buildbucket for the build status
[02:38:48.258] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:48.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:10:0) after 1m0s
[02:39:48.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:10:0)
[02:39:48.374] Timer tick, asking Buildbucket for the build status
[02:39:48.477] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:48.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:11:0) after 1m0s
[02:40:48.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:11:0)
[02:40:48.509] Timer tick, asking Buildbucket for the build status
[02:40:48.768] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:48.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:12:0) after 1m0s
[02:41:48.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:12:0)
[02:41:48.836] Timer tick, asking Buildbucket for the build status
[02:41:48.927] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:48.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:13:0) after 1m0s
[02:42:49.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:13:0)
[02:42:49.032] Timer tick, asking Buildbucket for the build status
[02:42:49.447] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:49.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:14:0) after 1m0s
[02:43:49.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:14:0)
[02:43:49.497] Timer tick, asking Buildbucket for the build status
[02:43:49.630] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:49.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:15:0) after 1m0s
[02:44:49.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:15:0)
[02:44:49.714] Timer tick, asking Buildbucket for the build status
[02:44:50.176] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:50.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:16:0) after 1m0s
[02:45:50.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:16:0)
[02:45:50.195] Timer tick, asking Buildbucket for the build status
[02:45:50.283] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:50.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:17:0) after 1m0s
[02:46:50.310] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:17:0)
[02:46:50.310] Timer tick, asking Buildbucket for the build status
[02:46:50.590] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:50.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:18:0) after 1m0s
[02:47:50.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:18:0)
[02:47:50.612] Timer tick, asking Buildbucket for the build status
[02:47:50.828] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:50.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:19:0) after 1m0s
[02:48:50.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:19:0)
[02:48:50.796] Timer tick, asking Buildbucket for the build status
[02:48:51.096] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:51.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:20:0) after 1m0s
[02:49:51.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:20:0)
[02:49:51.114] Timer tick, asking Buildbucket for the build status
[02:49:51.200] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:51.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:21:0) after 1m0s
[02:50:51.286] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:21:0)
[02:50:51.286] Timer tick, asking Buildbucket for the build status
[02:50:51.383] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:51.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:22:0) after 1m0s
[02:51:51.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:22:0)
[02:51:51.494] Timer tick, asking Buildbucket for the build status
[02:51:51.794] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:51.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:23:0) after 1m0s
[02:52:51.911] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:23:0)
[02:52:51.911] Timer tick, asking Buildbucket for the build status
[02:52:52.213] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:52.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:24:0) after 1m0s
[02:53:52.384] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:24:0)
[02:53:52.384] Timer tick, asking Buildbucket for the build status
[02:53:52.456] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:52.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:25:0) after 1m0s
[02:54:52.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:25:0)
[02:54:52.475] Timer tick, asking Buildbucket for the build status
[02:54:52.552] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:52.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:26:0) after 1m0s
[02:55:52.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:26:0)
[02:55:52.598] Timer tick, asking Buildbucket for the build status
[02:55:52.906] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:52.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:27:0) after 1m0s
[02:56:52.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:27:0)
[02:56:52.958] Timer tick, asking Buildbucket for the build status
[02:56:53.034] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:53.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:28:0) after 1m0s
[02:57:53.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:28:0)
[02:57:53.040] Timer tick, asking Buildbucket for the build status
[02:57:53.413] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:53.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:29:0) after 1m0s
[02:58:53.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:29:0)
[02:58:53.472] Timer tick, asking Buildbucket for the build status
[02:58:53.556] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:53.556] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:30:0) after 1m0s
[02:59:53.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:30:0)
[02:59:53.623] Timer tick, asking Buildbucket for the build status
[02:59:53.761] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:53.761] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:31:0) after 1m0s
[03:00:53.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:31:0)
[03:00:53.783] Timer tick, asking Buildbucket for the build status
[03:00:54.258] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:54.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:32:0) after 1m0s
[03:01:54.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:32:0)
[03:01:54.278] Timer tick, asking Buildbucket for the build status
[03:01:54.507] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:54.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:33:0) after 1m0s
[03:02:54.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:33:0)
[03:02:54.552] Timer tick, asking Buildbucket for the build status
[03:02:54.668] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:54.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:34:0) after 1m0s
[03:03:54.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:34:0)
[03:03:54.656] Timer tick, asking Buildbucket for the build status
[03:03:54.766] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:54.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:35:0) after 1m0s
[03:04:54.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:35:0)
[03:04:54.843] Timer tick, asking Buildbucket for the build status
[03:04:54.958] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:54.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:36:0) after 1m0s
[03:05:55.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:36:0)
[03:05:55.023] Timer tick, asking Buildbucket for the build status
[03:05:55.145] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:55.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:37:0) after 1m0s
[03:06:55.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:37:0)
[03:06:55.223] Timer tick, asking Buildbucket for the build status
[03:06:55.330] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:55.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:38:0) after 1m0s
[03:07:55.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:38:0)
[03:07:55.393] Timer tick, asking Buildbucket for the build status
[03:07:55.536] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:55.536] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:39:0) after 1m0s
[03:08:55.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:39:0)
[03:08:55.629] Timer tick, asking Buildbucket for the build status
[03:08:55.922] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:55.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:40:0) after 1m0s
[03:09:55.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:40:0)
[03:09:55.976] Timer tick, asking Buildbucket for the build status
[03:09:56.344] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:56.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:41:0) after 1m0s
[03:10:56.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:41:0)
[03:10:56.372] Timer tick, asking Buildbucket for the build status
[03:10:56.478] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:56.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:42:0) after 1m0s
[03:11:56.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:42:0)
[03:11:56.547] Timer tick, asking Buildbucket for the build status
[03:11:56.830] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:56.830] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:43:0) after 1m0s
[03:12:56.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:43:0)
[03:12:56.915] Timer tick, asking Buildbucket for the build status
[03:12:57.025] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:57.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:44:0) after 1m0s
[03:13:57.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:44:0)
[03:13:57.041] Timer tick, asking Buildbucket for the build status
[03:13:57.162] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:57.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:45:0) after 1m0s
[03:14:57.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:45:0)
[03:14:57.220] Timer tick, asking Buildbucket for the build status
[03:14:57.304] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:57.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:46:0) after 1m0s
[03:15:57.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:46:0)
[03:15:57.375] Timer tick, asking Buildbucket for the build status
[03:15:57.806] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:57.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:47:0) after 1m0s
[03:16:57.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:47:0)
[03:16:57.829] Timer tick, asking Buildbucket for the build status
[03:16:58.027] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:58.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:48:0) after 1m0s
[03:17:58.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:48:0)
[03:17:58.080] Timer tick, asking Buildbucket for the build status
[03:17:58.188] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:58.188] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:49:0) after 1m0s
[03:18:58.345] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:49:0)
[03:18:58.345] Timer tick, asking Buildbucket for the build status
[03:18:58.439] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:58.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:50:0) after 1m0s
[03:19:58.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:50:0)
[03:19:58.542] Timer tick, asking Buildbucket for the build status
[03:19:58.637] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:58.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:51:0) after 1m0s
[03:20:58.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:51:0)
[03:20:58.681] Timer tick, asking Buildbucket for the build status
[03:20:58.924] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:58.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:52:0) after 1m0s
[03:21:58.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:52:0)
[03:21:58.958] Timer tick, asking Buildbucket for the build status
[03:21:59.106] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:59.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:53:0) after 1m0s
[03:22:59.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:53:0)
[03:22:59.244] Timer tick, asking Buildbucket for the build status
[03:22:59.334] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:59.334] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:54:0) after 1m0s
[03:23:59.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:54:0)
[03:23:59.472] Timer tick, asking Buildbucket for the build status
[03:23:59.555] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:59.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:55:0) after 1m0s
[03:24:59.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:55:0)
[03:24:59.735] Timer tick, asking Buildbucket for the build status
[03:24:59.913] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:59.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:56:0) after 1m0s
[03:26:00.029] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:56:0)
[03:26:00.029] Timer tick, asking Buildbucket for the build status
[03:26:00.179] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:00.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:57:0) after 1m0s
[03:27:00.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:57:0)
[03:27:00.198] Timer tick, asking Buildbucket for the build status
[03:27:00.274] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:00.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:58:0) after 1m0s
[03:28:01.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:58:0)
[03:28:01.191] Timer tick, asking Buildbucket for the build status
[03:28:01.318] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:01.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:59:0) after 1m0s
[03:29:01.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:59:0)
[03:29:01.336] Timer tick, asking Buildbucket for the build status
[03:29:01.429] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:01.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:60:0) after 1m0s
[03:30:02.024] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:60:0)
[03:30:02.024] Timer tick, asking Buildbucket for the build status
[03:30:02.343] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:02.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:61:0) after 1m0s
[03:31:02.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:61:0)
[03:31:02.356] Timer tick, asking Buildbucket for the build status
[03:31:02.450] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:02.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:62:0) after 1m0s
[03:32:02.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:62:0)
[03:32:02.544] Timer tick, asking Buildbucket for the build status
[03:32:02.744] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:02.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:63:0) after 1m0s
[03:33:02.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:63:0)
[03:33:02.770] Timer tick, asking Buildbucket for the build status
[03:33:02.907] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:02.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:64:0) after 1m0s
[03:34:02.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:64:0)
[03:34:02.950] Timer tick, asking Buildbucket for the build status
[03:34:03.102] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:03.102] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:65:0) after 1m0s
[03:35:03.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:65:0)
[03:35:03.202] Timer tick, asking Buildbucket for the build status
[03:35:03.303] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:03.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:66:0) after 1m0s
[03:36:03.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:66:0)
[03:36:03.397] Timer tick, asking Buildbucket for the build status
[03:36:03.488] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:03.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:67:0) after 1m0s
[03:37:03.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:67:0)
[03:37:03.548] Timer tick, asking Buildbucket for the build status
[03:37:03.653] Build 8854451641430464304: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:03.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019881703519119696:68:0) after 1m0s
[03:37:37.427] Received PubSub notification, asking Buildbucket for the build status
[03:37:37.799] Build 8854451641430464304: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:37:37.799] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614137856147225",
"created_by": "project:chromium",
"created_ts": "1614133905914381",
"id": "8854451641430464304",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"3fea7574bf0beaafe6e53fedb422341e114a2194\"}}",
"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\": \"3fea7574bf0beaafe6e53fedb422341e114a2194\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17500, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"f19f319a59c83d9ccdce08a3b41024cfc997fd8d\", \"got_dawn_revision\": \"d67544797e54d3866f378c2a7c05181db552f125\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"3fea7574bf0beaafe6e53fedb422341e114a2194\", \"got_revision_cp\": \"refs/heads/master@{#856957}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"22186f076bf3bbe1a818792bde93b297e3773f7e\", \"got_v8_revision\": \"e6d93982be7f6f3686225c97ffe908935ef372dc\", \"got_v8_revision_cp\": \"refs/heads/9.0.242@{#1}\", \"got_webrtc_revision\": \"d0844a80deaab28d7df8e5dff9f71e6ab6919025\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33323}\", \"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\": \"3fea7574bf0beaafe6e53fedb422341e114a2194\", \"swarm_hashes_refs/heads/master(at){#856957}_without_patch\": {\"absl_hardening_tests\": \"c09b678be0ed806b6f0edbcdb13730c9d2b429e2\", \"accessibility_unittests\": \"a5dfa237fc10c613d7ad8893bf5b4d58f99bcda6\", \"angle_unittests\": \"9a33f2e51748be2af06bd7a07675f97ad4b9644a\", \"app_shell_unittests\": \"60b89e8e2d6e4709368837e18eb2407e8a55324d\", \"aura_unittests\": \"68e7da46c5b1989fbab793538209f9fc9a058006\", \"base_unittests\": \"c411129e74d83f53350e612d2464e96f29f3be3f\", \"base_util_unittests\": \"a941540828cdc9294a8f290e1136da7297fe21f9\", \"blink_common_unittests\": \"39c0bc04811163ce78ef12154c4d979e514befdd\", \"blink_fuzzer_unittests\": \"f340c50a42a815aa95b5ef556a5a293dc14b88c6\", \"blink_heap_unittests\": \"724a7619f185250f9819d1657ab0b493b3921a7e\", \"blink_platform_unittests\": \"ab2c03987d37d231aa50ee24216cd81053e58062\", \"blink_unittests\": \"3dcb4ef4bb0ef91e8446cc2af9ce2ac3bd2f388b\", \"boringssl_crypto_tests\": \"eaffa5d12adf692bdb7e8fa939898fe908aba020\", \"boringssl_ssl_tests\": \"b90a5dcfcf288c06f1c7b510298b340cccdb39bf\", \"browser_switcher_bho_unittests\": \"502055f7ba822262a52281d357953ba709b5880c\", \"browser_tests\": \"8e4db9f2013c420e8106f9ea0c29dba0faec8d55\", \"capture_unittests\": \"107e288772b609d8bf84d050d96cf506ddea3dd2\", \"cast_unittests\": \"8606e4087fbc8bc4177a1f41373ea7487720d104\", \"cc_unittests\": \"e41d0d50c4004dfa61af86e26e9b5caf73c65710\", \"chrome_app_unittests\": \"cdb5c559f3fb039dc52b5fa6c5494355e83ad68b\", \"chrome_cleaner_unittests\": \"13a1075da8162eafe21924f99fe5f2e089f63fc0\", \"chrome_elf_unittests\": \"90758a9a4d0fd79552ceb320aea84785a8ca8baf\", \"chromedriver_unittests\": \"c6ef0b9a810a84634fbe066200148acbac86ab68\", \"color_unittests\": \"b3762d3f27c62f75f667e6711eb8bc113744f71a\", \"components_browsertests\": \"e14515873430634ad97e7f222549e02e9073c826\", \"components_unittests\": \"30cc117df31edb9fa7957d68afcaba8d790ed9fa\", \"compositor_unittests\": \"4a7e472271effe0864df98e26b596a9d0d0329ba\", \"content_browsertests\": \"7ed65413a319ea932fdf237812783468d869fd7d\", \"content_unittests\": \"6769990a15c4e95b80b75e62e5842b3f25637597\", \"courgette_unittests\": \"5e5f411c1842bcda1df7c4881aeac1fe9c2d6db5\", \"crashpad_tests\": \"02299e5d3d0577ee61f53495d06d069e4d20744e\", \"cronet_tests\": \"a4635c763890af63ad7a70a7749dd18b6e2ebdc3\", \"cronet_unittests\": \"290eef0c0b064ed963fe5551db73e82792fef963\", \"crypto_unittests\": \"77790055e53a525dc18193822ac154df6e0e7705\", \"delayloads_unittests\": \"8a273a06bca12b3f3d8b2d1c4dd0f80430b1dcb2\", \"device_unittests\": \"97a990759e865f922849779b385713f7ac58f6fd\", \"display_unittests\": \"38ac9d5287484aba8c12a0b53634f416d4dcc98b\", \"elevation_service_unittests\": \"5ae6c2bdee9bf342391a7d67418242b7b1c71cfa\", \"events_unittests\": \"a3ebfad03689fbfbef678b69141cf06a72648642\", \"extensions_browsertests\": \"db39cc2764c014c23843660a64b300fb7ab2a447\", \"extensions_unittests\": \"3fb8d3b9f6983fa18e38c6afab104a731b7c7186\", \"filesystem_service_unittests\": \"20f491b912ef899c049bd23207fba6e0bc4c21b0\", \"gcm_unit_tests\": \"22b77c6058be783d9830aa46057c252d387d1f40\", \"gcp_unittests\": \"e292c2e95bfa1598635cfcdc79b425d7506b7dc5\", \"gfx_unittests\": \"06afe9c382aaacad90d14f7f3dc3d695ff54e6d0\", \"gin_unittests\": \"5a9875cb36bb26653c9cbbab09f959e11c282fd4\", \"google_apis_unittests\": \"b7467852cb51d30c0f1e7869eef4d7573f239794\", \"gpu_unittests\": \"2a2116e1381963883ef559cee2ac653ce3f67ee0\", \"gwp_asan_unittests\": \"93e7dedd71ad06ca4e9731928c85687ae8303eb8\", \"headless_browsertests\": \"61342d5bf80d96cd932f8a1a9a4ada3e2f6a5b66\", \"headless_unittests\": \"a7fdb6d377c693fb8ed1210f192d2484a35ae09c\", \"install_static_unittests\": \"85020ea57cbd0ce57d0dd855b51c7daf9719d4a6\", \"installer_util_unittests\": \"61e514869b8092fe3a7c71df21d6b5b6a5ac06f5\", \"interactive_ui_tests\": \"729af11acca68cafa0870eca289a189e8a4a0ed7\", \"ipc_tests\": \"8305f256237bbeb0959ce8765701336eb54f4b05\", \"jingle_unittests\": \"f5166733d4bb13aa765deb76cc8a37ce39798ab6\", \"latency_unittests\": \"0808de0944b7b084caf4b8dcefccf22bb177b4ab\", \"libjingle_xmpp_unittests\": \"e6239c02e2291c91229ce649daa90d9f723d95a5\", \"liburlpattern_unittests\": \"f28715e14064e19e541ca80fce0f481a04959edb\", \"media_blink_unittests\": \"654dd052864fdc34b065142278ce038adc31cf04\", \"media_unittests\": \"984bc26898fa01f5890a1243397d756dc852412e\", \"message_center_unittests\": \"6931df6bb6aa03628cbc94d85693849151213276\", \"midi_unittests\": \"d04f8ba7d6ab9003b099c469305791ef5e9857b9\", \"mojo_core_unittests\": \"d89a4400c4185bf9aad33000eee2b4b2eeb3fe06\", \"mojo_unittests\": \"196cb1143d7f700c56ef04ef4de2330aa84befd5\", \"nacl_loader_unittests\": \"8ea8cc960bbecdd726fab4ab294444811c1da40c\", \"native_theme_unittests\": \"e13cb33d75f08e4b687c264571fbb26f1ba7c9f1\", \"net_unittests\": \"291e7c9ffb0e6f324af719447f7e8db2435d6163\", \"notification_helper_unittests\": \"db6e0bb13a9426872cd66ed58f29637b38c15516\", \"pdf_unittests\": \"1cd50ade66f0ae708492d6da82ed763244fd0283\", \"perfetto_unittests\": \"69e39dd837ab596b3cd4b1694963355a1f38279b\", \"ppapi_unittests\": \"b8161fb788ca5cbd43c6d7fe92946eb7b1a8deef\", \"printing_unittests\": \"0c84fe358de8859a2a96d4467b947290bddacd52\", \"remoting_unittests\": \"0fc13f498c7109dc96da72991273ae27c6da7cdb\", \"sbox_integration_tests\": \"7f7d39e2bac7ce89ef60f1e0a4bd9250756285dc\", \"sbox_unittests\": \"cdb819388ceb08f4e441401c98816ca204167a9b\", \"sbox_validation_tests\": \"1c33e4b9e8aad7866111db259bf54c3a86256e77\", \"service_manager_unittests\": \"a16ab136a60fe90588b402a82c60daf7273bec52\", \"services_unittests\": \"1497630f3cf67e021bcb4b68e961f9d0fb7c1385\", \"setup_unittests\": \"64dc8f32e353cb227852426218901bdea95436eb\", \"shell_dialogs_unittests\": \"365d8643d3d9e01972c682a206d200c87fa682ac\", \"skia_unittests\": \"2f85f3cd2499b86445491a88710ccaf89265e005\", \"snapshot_unittests\": \"8c707c40c4c6b3dec9230e45e4d89f6112014f34\", \"sql_unittests\": \"6dc93b26201fd4dc78f4db2274331a1b2a559899\", \"storage_unittests\": \"608900f7023be6c9d41b34b9587cfc1f3e531d9f\", \"sync_integration_tests\": \"10ac21d2c7cb920f82a7e1aa290aaa7b83fdda22\", \"traffic_annotation_auditor_unittests\": \"349e9c74ae1c6ec1243b4ff926301acfc05f1985\", \"ui_base_unittests\": \"5c132efacac15e29a8d159219b6d4b0b23d938e2\", \"ui_touch_selection_unittests\": \"fbf141a6c91e87a098d36a139d829addeda16a42\", \"unit_tests\": \"f60e9160658f23ac424155131df3235f194b5b2e\", \"updater_tests\": \"6fcd679a4c66b1c9bf8005603c1f32fa6743cf22\", \"url_unittests\": \"0a2697a04bc7438c2f43f7e2cd8451029093f106\", \"views_unittests\": \"eb83db98afd9129f0316b7cf417a339c1d126837\", \"viz_unittests\": \"ecf56867b89c8322bc2fd0546df062325ef10457\", \"vr_common_unittests\": \"99fa7d6f23b388bba3f90e514d2ecae7a3578f0a\", \"vr_pixeltests\": \"22b58888eda5b388ab86f9782275d2ba83a30652\", \"weblayer_browsertests\": \"75d3de94e64fe4f2833af5e83e6c45048fb6f09b\", \"weblayer_unittests\": \"41f9067d8c97cb59bf256eec2903508c1a8b7f63\", \"wm_unittests\": \"07c6142da191170516609b696ae551e6cbe83b26\", \"wtf_unittests\": \"2eca52cba1fd659a744e73dce480510e75b78cda\", \"zlib_unittests\": \"a890387f5c9a88dc3667795f0feb5e093dc32e52\", \"zucchini_unittests\": \"bb881c14deea9e67d74c03ecccda6383108c6dfa\"}}, \"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-3-xpf4\"], \"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\": [\"5709-d3c4474\"], \"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": "1614133917516297",
"status": "COMPLETED",
"status_changed_ts": "1614137856548413",
"tags": [
"build_address:luci.chromium.ci/win-asan/17500",
"builder:win-asan",
"buildset:commit/git/3fea7574bf0beaafe6e53fedb422341e114a2194",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/3fea7574bf0beaafe6e53fedb422341e114a2194",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019881703519119696",
"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/8854451641430464304/+/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:51eab279a9003b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614137856555668",
"url": "https://ci.chromium.org/b/8854451641430464304",
"utcnow_ts": "1614137857750316"
}
}
[03:37:37.799] Invocation finished in 1h5m53.260857734s with status SUCCEEDED