[03:49:16.554] New invocation is queued and will start shortly
[03:49:17.792] Starting the invocation (attempt 1)
[03:49:17.824] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:49:17.824] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:49:17.824] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019695631619882576",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2e139e43e1891692b4e67f391432b0b95861f881\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019695631619882576",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2e139e43e1891692b4e67f391432b0b95861f881",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e139e43e1891692b4e67f391432b0b95861f881",
"gitiles_ref:refs/heads/master"
]
}
[03:49:18.576] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614311357912657",
"id": "8854265569524433888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2e139e43e1891692b4e67f391432b0b95861f881\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614311358341356",
"tags": [
"build_address:luci.chromium.ci/win-asan/17538",
"builder:win-asan",
"buildset:commit/git/2e139e43e1891692b4e67f391432b0b95861f881",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e139e43e1891692b4e67f391432b0b95861f881",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019695631619882576",
"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/8854265569524433888/+/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": "1614311358341474",
"url": "https://ci.chromium.org/b/8854265569524433888",
"utcnow_ts": "1614311358568612"
}
}
[03:49:18.576] Task URL: https://ci.chromium.org/b/8854265569524433888
[03:49:18.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:2:0) after 1m0s
[03:49:22.972] Received PubSub notification, asking Buildbucket for the build status
[03:49:23.054] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:18.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:2:0)
[03:50:18.579] Timer tick, asking Buildbucket for the build status
[03:50:18.654] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:18.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:4:0) after 1m0s
[03:51:18.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:4:0)
[03:51:18.646] Timer tick, asking Buildbucket for the build status
[03:51:18.799] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:18.799] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:5:0) after 1m0s
[03:52:18.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:5:0)
[03:52:18.830] Timer tick, asking Buildbucket for the build status
[03:52:18.939] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:18.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:6:0) after 1m0s
[03:53:19.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:6:0)
[03:53:19.078] Timer tick, asking Buildbucket for the build status
[03:53:19.179] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:19.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:7:0) after 1m0s
[03:54:19.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:7:0)
[03:54:19.150] Timer tick, asking Buildbucket for the build status
[03:54:19.222] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:19.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:8:0) after 1m0s
[03:55:19.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:8:0)
[03:55:19.282] Timer tick, asking Buildbucket for the build status
[03:55:19.354] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:19.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:9:0) after 1m0s
[03:56:19.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:9:0)
[03:56:19.408] Timer tick, asking Buildbucket for the build status
[03:56:19.563] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:19.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:10:0) after 1m0s
[03:57:19.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:10:0)
[03:57:19.615] Timer tick, asking Buildbucket for the build status
[03:57:19.732] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:19.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:11:0) after 1m0s
[03:58:19.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:11:0)
[03:58:19.727] Timer tick, asking Buildbucket for the build status
[03:58:19.887] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:19.887] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:12:0) after 1m0s
[03:59:20.137] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:12:0)
[03:59:20.137] Timer tick, asking Buildbucket for the build status
[03:59:20.367] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:20.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:13:0) after 1m0s
[04:00:20.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:13:0)
[04:00:20.595] Timer tick, asking Buildbucket for the build status
[04:00:21.530] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:21.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:14:0) after 1m0s
[04:01:21.606] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:14:0)
[04:01:21.606] Timer tick, asking Buildbucket for the build status
[04:01:21.685] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:21.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:15:0) after 1m0s
[04:02:21.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:15:0)
[04:02:21.759] Timer tick, asking Buildbucket for the build status
[04:02:21.915] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:21.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:16:0) after 1m0s
[04:03:21.980] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:16:0)
[04:03:21.980] Timer tick, asking Buildbucket for the build status
[04:03:22.098] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:22.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:17:0) after 1m0s
[04:04:22.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:17:0)
[04:04:22.120] Timer tick, asking Buildbucket for the build status
[04:04:22.219] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:22.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:18:0) after 1m0s
[04:05:22.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:18:0)
[04:05:22.264] Timer tick, asking Buildbucket for the build status
[04:05:22.343] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:22.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:19:0) after 1m0s
[04:06:22.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:19:0)
[04:06:22.409] Timer tick, asking Buildbucket for the build status
[04:06:22.502] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:22.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:20:0) after 1m0s
[04:07:22.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:20:0)
[04:07:22.612] Timer tick, asking Buildbucket for the build status
[04:07:22.921] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:22.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:21:0) after 1m0s
[04:08:22.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:21:0)
[04:08:22.949] Timer tick, asking Buildbucket for the build status
[04:08:23.025] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:23.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:22:0) after 1m0s
[04:09:23.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:22:0)
[04:09:23.075] Timer tick, asking Buildbucket for the build status
[04:09:23.172] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:23.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:23:0) after 1m0s
[04:10:23.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:23:0)
[04:10:23.253] Timer tick, asking Buildbucket for the build status
[04:10:23.772] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:23.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:24:0) after 1m0s
[04:11:23.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:24:0)
[04:11:23.841] Timer tick, asking Buildbucket for the build status
[04:11:23.903] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:23.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:25:0) after 1m0s
[04:12:23.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:25:0)
[04:12:23.972] Timer tick, asking Buildbucket for the build status
[04:12:24.071] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:24.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:26:0) after 1m0s
[04:13:24.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:26:0)
[04:13:24.124] Timer tick, asking Buildbucket for the build status
[04:13:24.904] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:24.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:27:0) after 1m0s
[04:14:24.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:27:0)
[04:14:24.915] Timer tick, asking Buildbucket for the build status
[04:14:25.435] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:25.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:28:0) after 1m0s
[04:15:25.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:28:0)
[04:15:25.453] Timer tick, asking Buildbucket for the build status
[04:15:25.722] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:25.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:29:0) after 1m0s
[04:16:25.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:29:0)
[04:16:25.763] Timer tick, asking Buildbucket for the build status
[04:16:25.846] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:25.846] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:30:0) after 1m0s
[04:17:25.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:30:0)
[04:17:25.822] Timer tick, asking Buildbucket for the build status
[04:17:25.950] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:25.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:31:0) after 1m0s
[04:18:25.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:31:0)
[04:18:25.986] Timer tick, asking Buildbucket for the build status
[04:18:26.254] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:26.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:32:0) after 1m0s
[04:19:26.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:32:0)
[04:19:26.248] Timer tick, asking Buildbucket for the build status
[04:19:26.355] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:26.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:33:0) after 1m0s
[04:20:26.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:33:0)
[04:20:26.375] Timer tick, asking Buildbucket for the build status
[04:20:26.475] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:26.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:34:0) after 1m0s
[04:21:26.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:34:0)
[04:21:26.496] Timer tick, asking Buildbucket for the build status
[04:21:26.576] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:26.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:35:0) after 1m0s
[04:22:26.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:35:0)
[04:22:26.589] Timer tick, asking Buildbucket for the build status
[04:22:26.674] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:26.674] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:36:0) after 1m0s
[04:23:26.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:36:0)
[04:23:26.723] Timer tick, asking Buildbucket for the build status
[04:23:27.182] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:27.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:37:0) after 1m0s
[04:24:28.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:37:0)
[04:24:28.123] Timer tick, asking Buildbucket for the build status
[04:24:28.253] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:28.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:38:0) after 1m0s
[04:25:28.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:38:0)
[04:25:28.329] Timer tick, asking Buildbucket for the build status
[04:25:28.418] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:28.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:39:0) after 1m0s
[04:26:28.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:39:0)
[04:26:28.640] Timer tick, asking Buildbucket for the build status
[04:26:28.808] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:28.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:40:0) after 1m0s
[04:27:28.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:40:0)
[04:27:28.985] Timer tick, asking Buildbucket for the build status
[04:27:29.072] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:29.072] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:41:0) after 1m0s
[04:28:29.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:41:0)
[04:28:29.161] Timer tick, asking Buildbucket for the build status
[04:28:29.557] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:29.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:42:0) after 1m0s
[04:29:29.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:42:0)
[04:29:29.678] Timer tick, asking Buildbucket for the build status
[04:29:29.777] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:29.777] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:43:0) after 1m0s
[04:30:29.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:43:0)
[04:30:29.879] Timer tick, asking Buildbucket for the build status
[04:30:29.958] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:29.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:44:0) after 1m0s
[04:31:29.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:44:0)
[04:31:29.992] Timer tick, asking Buildbucket for the build status
[04:31:30.261] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:30.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:45:0) after 1m0s
[04:32:30.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:45:0)
[04:32:30.476] Timer tick, asking Buildbucket for the build status
[04:32:30.602] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:30.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:46:0) after 1m0s
[04:33:30.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:46:0)
[04:33:30.752] Timer tick, asking Buildbucket for the build status
[04:33:33.280] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:33.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:47:0) after 1m0s
[04:34:33.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:47:0)
[04:34:33.299] Timer tick, asking Buildbucket for the build status
[04:34:33.450] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:33.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:48:0) after 1m0s
[04:35:33.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:48:0)
[04:35:33.530] Timer tick, asking Buildbucket for the build status
[04:35:33.618] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:33.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:49:0) after 1m0s
[04:36:33.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:49:0)
[04:36:33.750] Timer tick, asking Buildbucket for the build status
[04:36:33.915] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:33.915] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:50:0) after 1m0s
[04:37:34.018] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:50:0)
[04:37:34.018] Timer tick, asking Buildbucket for the build status
[04:37:34.823] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:34.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:51:0) after 1m0s
[04:38:34.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:51:0)
[04:38:34.882] Timer tick, asking Buildbucket for the build status
[04:38:34.981] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:34.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:52:0) after 1m0s
[04:39:35.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:52:0)
[04:39:35.055] Timer tick, asking Buildbucket for the build status
[04:39:35.189] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:35.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:53:0) after 1m0s
[04:40:35.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:53:0)
[04:40:35.276] Timer tick, asking Buildbucket for the build status
[04:40:35.746] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:35.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:54:0) after 1m0s
[04:41:35.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:54:0)
[04:41:35.740] Timer tick, asking Buildbucket for the build status
[04:41:36.226] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:36.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:55:0) after 1m0s
[04:42:36.308] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:55:0)
[04:42:36.308] Timer tick, asking Buildbucket for the build status
[04:42:36.385] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:36.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:56:0) after 1m0s
[04:43:36.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:56:0)
[04:43:36.477] Timer tick, asking Buildbucket for the build status
[04:43:36.598] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:36.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:57:0) after 1m0s
[04:44:36.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:57:0)
[04:44:36.687] Timer tick, asking Buildbucket for the build status
[04:44:36.804] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:36.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:58:0) after 1m0s
[04:45:36.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:58:0)
[04:45:36.798] Timer tick, asking Buildbucket for the build status
[04:45:36.904] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:36.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:59:0) after 1m0s
[04:46:37.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:59:0)
[04:46:37.008] Timer tick, asking Buildbucket for the build status
[04:46:37.125] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:37.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:60:0) after 1m0s
[04:47:37.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:60:0)
[04:47:37.142] Timer tick, asking Buildbucket for the build status
[04:47:37.217] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:37.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:61:0) after 1m0s
[04:48:37.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:61:0)
[04:48:37.274] Timer tick, asking Buildbucket for the build status
[04:48:37.356] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:37.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:62:0) after 1m0s
[04:49:37.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:62:0)
[04:49:37.376] Timer tick, asking Buildbucket for the build status
[04:49:37.563] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:37.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:63:0) after 1m0s
[04:50:37.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:63:0)
[04:50:37.722] Timer tick, asking Buildbucket for the build status
[04:50:37.809] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:37.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:64:0) after 1m0s
[04:51:37.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:64:0)
[04:51:37.857] Timer tick, asking Buildbucket for the build status
[04:51:37.999] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:37.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:65:0) after 1m0s
[04:52:38.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:65:0)
[04:52:38.028] Timer tick, asking Buildbucket for the build status
[04:52:38.118] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:66:0) after 1m0s
[04:53:38.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:66:0)
[04:53:38.097] Timer tick, asking Buildbucket for the build status
[04:53:38.186] Build 8854265569524433888: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:38.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019695631619882576:67:0) after 1m0s
[04:53:52.979] Received PubSub notification, asking Buildbucket for the build status
[04:53:53.058] Build 8854265569524433888: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:53:53.058] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614315231993156",
"created_by": "project:chromium",
"created_ts": "1614311357912657",
"id": "8854265569524433888",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2e139e43e1891692b4e67f391432b0b95861f881\"}}",
"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\": \"2e139e43e1891692b4e67f391432b0b95861f881\", \"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\": 17538, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"77637f2dd222530c721018185a90e24a51e7a745\", \"got_dawn_revision\": \"545a6970cbe9e986dd25e01f7ecf2f9a1b17c756\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"2e139e43e1891692b4e67f391432b0b95861f881\", \"got_revision_cp\": \"refs/heads/master@{#858014}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"bb52bdf09516ca548c4aff50526eda561f239bc0\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33341}\", \"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\": \"2e139e43e1891692b4e67f391432b0b95861f881\", \"swarm_hashes_refs/heads/master(at){#858014}_without_patch\": {\"absl_hardening_tests\": \"81450d4a59cf9e11196375f7c6c8c515700de2b8\", \"accessibility_unittests\": \"729692606eb2f8e29f601c4736b4463f160e4925\", \"angle_unittests\": \"c95fc065ac8df3967b1c14cf8c25f6b04e407a3f\", \"app_shell_unittests\": \"ce18794c665f81f1f505f1d32a159ba8730bbccd\", \"aura_unittests\": \"539e3e88a51c944861d151dd2ab275ca0f348204\", \"base_unittests\": \"80e59d873cbf905d88c1b7956627e5ecfcffcc97\", \"base_util_unittests\": \"183ea2f869a5bacacb92e1d4efa996d21f62c1b5\", \"blink_common_unittests\": \"396dd2028b3a9a5ad9b9978bfcd4a38b37fb1e6f\", \"blink_fuzzer_unittests\": \"11751e62b6cf594c59997f1562e586d97b5f5719\", \"blink_heap_unittests\": \"d37e93f81a164e780ba66003ced59fb1d6e27902\", \"blink_platform_unittests\": \"0e587d6b844c1a72fc5b95595c82d332de1c377e\", \"blink_unittests\": \"50fb6613b45d8f670f1966ca7c9cb6653f0b88e0\", \"boringssl_crypto_tests\": \"674ab1de077ecbf293466d4cd9be4a9ce028af5c\", \"boringssl_ssl_tests\": \"9c2e930ff8b74248dbb8f2c686bde225d63fe1b2\", \"browser_switcher_bho_unittests\": \"e6bf1ef4ede47b01747b72e946ac79e27de81f8c\", \"browser_tests\": \"657a6e16e817418673b4f01788fed99599600a62\", \"capture_unittests\": \"28647aaae4be946afa3167b05764bcc7c688a8f4\", \"cast_unittests\": \"18e4f31a295e45ba237503c562371768689249b9\", \"cc_unittests\": \"f7e4a035337a2cdc2656717a420bc2a73478bc9e\", \"chrome_app_unittests\": \"4e1943fa6164e8d0e9e0875faf5b541315dde483\", \"chrome_cleaner_unittests\": \"110635eadd7a33429f2e704273d19ee86a76bf48\", \"chrome_elf_unittests\": \"4f44e1a555feeef2ac40c37b7dd906d3bf68bd13\", \"chromedriver_unittests\": \"be3a646a25b2ce6912298945c836f03ba4c2bd82\", \"color_unittests\": \"869d07a493e069e298a79826d2ee0de3576236f4\", \"components_browsertests\": \"f185448a6d94c6798bfdcff337eb7e768fb7689f\", \"components_unittests\": \"a93d70718d2932f427c8410c941edde09535bbff\", \"compositor_unittests\": \"6ea2bb892b21a419cd98c3426e9787f76fad1f26\", \"content_browsertests\": \"d62b9adbeb55c378e34e0f405f0bdee819dd860a\", \"content_unittests\": \"1a0937f029cc2617aabdfb02de47db4adbcf86e7\", \"courgette_unittests\": \"c1b9625b0b838aeee98d56a7a29d6730a773af97\", \"crashpad_tests\": \"a2c135a197ccc896cab4bffb7654587287aa5ef8\", \"cronet_tests\": \"7ed72670d93c82ed8f309e6ee595f9911fc4c3b8\", \"cronet_unittests\": \"893f579618ed3a41ef7486833e444bf4924c2c95\", \"crypto_unittests\": \"810f93774b6de6e1c72b90ecf67e53fb0ab2379b\", \"delayloads_unittests\": \"0f204b89cba0bd46c01d5e3f1cf823877885e66b\", \"device_unittests\": \"674e7f7b44c6be0624b065de81b535bdac41a4df\", \"display_unittests\": \"c6095051ad569c82283a915b54925eeee067f823\", \"elevation_service_unittests\": \"029d81035b461a0a7b68bfe1bb74a59658d490a2\", \"events_unittests\": \"d8bb471c142997bf735ebfbfd4dc04805006d09e\", \"extensions_browsertests\": \"c2f6bf0d4dab0423e5e477e8decfd438a2cd48dc\", \"extensions_unittests\": \"7e9a279e29d6c50c3e6346d52e8dd9656e05a33d\", \"filesystem_service_unittests\": \"08714ad513789e611f702091340387761640ec60\", \"gcm_unit_tests\": \"835c28d3d95606856343d9b9e39f4be911b8bfb3\", \"gcp_unittests\": \"f775c21d7b367090cd6c78016a60032c35bc3e48\", \"gfx_unittests\": \"4c6b02c3d3e6f8fb591c50658a7f74586c888320\", \"gin_unittests\": \"77a9064652b5990f17c87d035091a2eba8cc212d\", \"google_apis_unittests\": \"ed395b52bf5758ba23d5ba8d25df399dd356cd69\", \"gpu_unittests\": \"7ed1c2660bcd3c2c22f8ed5e6a836e729c82f2e6\", \"gwp_asan_unittests\": \"fba4bda01bdc4c369ac1aaae1a3b1814c13cf30e\", \"headless_browsertests\": \"1d883726f438976a8fd8b602497aad80051bbb2a\", \"headless_unittests\": \"884b6821c9b7f94ff5c04f4b9b2d9ab1df3bec93\", \"install_static_unittests\": \"a670959d959e62d55abf61bab9dc1452ab0fd441\", \"installer_util_unittests\": \"fabeaabe4c7e92e6031fd01adeeecea4a4a3d54e\", \"interactive_ui_tests\": \"fd78ea3372496f30d2b2b4f3eaaa0bcd1412ca42\", \"ipc_tests\": \"a06227c78bf1e818edd281e0b6334c7b9ce60def\", \"jingle_unittests\": \"8ffcb7777e400430f152158172ecc24b58554b02\", \"latency_unittests\": \"866b1ad00442e185f9a6a50adf3a789533761d67\", \"libjingle_xmpp_unittests\": \"fab0019103a07408bb35fb9b0bbb9fe7777a5293\", \"liburlpattern_unittests\": \"cd4b5716a0d5f5d2c2b19bf957292b58915f9a62\", \"media_blink_unittests\": \"3e842a0ecbdd6f2313ba9c59eef0537ce33b9875\", \"media_unittests\": \"369b27da5d3f56afb49c697b37c32c7d7acfe8d7\", \"message_center_unittests\": \"2ac8821e9f63a051f20bfd8936ad6312dfc9a92f\", \"midi_unittests\": \"7485e1d825f0d31412aea10ce9bb16b1c708fac7\", \"mojo_core_unittests\": \"6ed81928f14e680ed1c6c422dafd1b632f0d3423\", \"mojo_unittests\": \"39969d6a3ba8bdd4079d377291d8a8d60e48541f\", \"nacl_loader_unittests\": \"8eeebeaf6fc998fee06d0308d246d0a02c5ca6c9\", \"native_theme_unittests\": \"02ee7c579656cc44011c9c46e7cdfd1680f0da37\", \"net_unittests\": \"e9809cb9f47a8fcd519c277a6dbb5e1560c73110\", \"notification_helper_unittests\": \"752d25a6fe0944781db01db76e34d4c69eab0a40\", \"pdf_unittests\": \"928b8578cb554cc93efe4aeab8ce0992fbe097fd\", \"perfetto_unittests\": \"212f88040123494d46da09f0d65a3d4929569640\", \"ppapi_unittests\": \"3c49920f5775e753c4e9cefebc351dd03e69bbe4\", \"printing_unittests\": \"4935ed07abc19f415067419417890da3e1645174\", \"remoting_unittests\": \"65203a9145184d83eddcc507148ad385a9eb6a8a\", \"sbox_integration_tests\": \"761e3f270e3bf207779a054b7e7938690a63bdc2\", \"sbox_unittests\": \"9072afd83d4383c1dd069c4ce494010bf63a438f\", \"sbox_validation_tests\": \"764495ee09df7ee67b33bec8f0180e5bb2aed46c\", \"service_manager_unittests\": \"c15cfdeb7721eeb98a6137d374d52bc8de2a64d0\", \"services_unittests\": \"9d36a5e3f55807025b8b710ca5bb20ac18656c6c\", \"setup_unittests\": \"8934054a07a7fc1bb1603c170445b6d58c9af612\", \"shell_dialogs_unittests\": \"25f9b94ad5312ef961fd34880adb83a824ae7611\", \"skia_unittests\": \"aea5e05df1fd7493f56472f91c2f314ea0fe874d\", \"snapshot_unittests\": \"6831f43bb06df9474d766ffd0416478f6edbbbe9\", \"sql_unittests\": \"157d0c83a4e242868da944f17767efe96ba9c262\", \"storage_unittests\": \"2777030bb3c7443aae9edaab114f3177665d530b\", \"sync_integration_tests\": \"75f44e078b29ec453ff067af89b6b8caa5e9f0fd\", \"traffic_annotation_auditor_unittests\": \"dadcf5abbc499a3cc19547bd000b5924fa26cc86\", \"ui_base_unittests\": \"5916ee497356c6cf9a9e066f1837dacced8dab75\", \"ui_touch_selection_unittests\": \"236831e977fa7e04f7c1623a66607696f3271c19\", \"unit_tests\": \"63b133e8a84fc48df1a4e039790f0d792e263955\", \"updater_tests\": \"877e83e074a4b1e8b7883e6e746eb628e4038c8c\", \"url_unittests\": \"3f7d5a64493e72e620be6314fdd2a21d5353a2a0\", \"views_unittests\": \"478b9c0d7353aa9f0438bf195016ed2df2736e1f\", \"viz_unittests\": \"c56b62a7658b4f8616389a31f82b9645d7c45b0b\", \"vr_common_unittests\": \"205a97b9ad11f4f20c7173bbb342816a51dc3d8a\", \"vr_pixeltests\": \"9f8b1ced080aac3b4a999027c2b535acfc1bbb73\", \"weblayer_browsertests\": \"c7e3163ff71a22c80fefd2861d6d1d347c3e8126\", \"weblayer_unittests\": \"6378402afc83ea6e008de8519e0c97ef5cd21d6a\", \"wm_unittests\": \"e7c3f1bbc1f0794163d1361dfb46f41023dfe19a\", \"wtf_unittests\": \"5d95085471b5c1dfb719657e227996587453341b\", \"zlib_unittests\": \"1e2790495518a78946c0bf8e63ab5d56caea82bd\", \"zucchini_unittests\": \"9d9838d7ad18fa13984eaf20affc280c4ed32692\"}}, \"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\": [\"5712-339372b\"], \"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": "1614311361939842",
"status": "COMPLETED",
"status_changed_ts": "1614315232494239",
"tags": [
"build_address:luci.chromium.ci/win-asan/17538",
"builder:win-asan",
"buildset:commit/git/2e139e43e1891692b4e67f391432b0b95861f881",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2e139e43e1891692b4e67f391432b0b95861f881",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019695631619882576",
"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/8854265569524433888/+/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:51f54609e95cc410",
"user_agent:luci-scheduler"
],
"updated_ts": "1614315232568250",
"url": "https://ci.chromium.org/b/8854265569524433888",
"utcnow_ts": "1614315233050500"
}
}
[04:53:53.058] Invocation finished in 1h4m36.528139882s with status SUCCEEDED