[12:09:18.310] New invocation is queued and will start shortly
[12:09:19.467] Starting the invocation (attempt 1)
[12:09:19.504] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:09:19.508] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:09:19.508] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018486411927878736",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"c3fb27225edefafd9cb94fb66c3134b247e6ace7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018486411927878736",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/c3fb27225edefafd9cb94fb66c3134b247e6ace7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3fb27225edefafd9cb94fb66c3134b247e6ace7",
"gitiles_ref:refs/heads/master"
]
}
[12:09:20.085] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615464559585864",
"id": "8853056349926767552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c3fb27225edefafd9cb94fb66c3134b247e6ace7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615464559990909",
"tags": [
"build_address:luci.chromium.ci/win-asan/17754",
"builder:win-asan",
"buildset:commit/git/c3fb27225edefafd9cb94fb66c3134b247e6ace7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3fb27225edefafd9cb94fb66c3134b247e6ace7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018486411927878736",
"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/8853056349926767552/+/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": "1615464559991017",
"url": "https://ci.chromium.org/b/8853056349926767552",
"utcnow_ts": "1615464560079237"
}
}
[12:09:20.085] Task URL: https://ci.chromium.org/b/8853056349926767552
[12:09:20.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:2:0) after 1m0s
[12:09:21.636] Received PubSub notification, asking Buildbucket for the build status
[12:09:21.818] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:20.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:2:0)
[12:10:20.119] Timer tick, asking Buildbucket for the build status
[12:10:20.235] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:10:20.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:4:0) after 1m0s
[12:11:20.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:4:0)
[12:11:20.270] Timer tick, asking Buildbucket for the build status
[12:11:20.421] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:11:20.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:5:0) after 1m0s
[12:12:20.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:5:0)
[12:12:20.448] Timer tick, asking Buildbucket for the build status
[12:12:20.645] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:12:20.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:6:0) after 1m0s
[12:13:20.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:6:0)
[12:13:20.663] Timer tick, asking Buildbucket for the build status
[12:13:20.981] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:13:20.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:7:0) after 1m0s
[12:14:21.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:7:0)
[12:14:21.037] Timer tick, asking Buildbucket for the build status
[12:14:21.461] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:14:21.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:8:0) after 1m0s
[12:15:21.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:8:0)
[12:15:21.488] Timer tick, asking Buildbucket for the build status
[12:15:21.567] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:15:21.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:9:0) after 1m0s
[12:16:21.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:9:0)
[12:16:21.630] Timer tick, asking Buildbucket for the build status
[12:16:21.812] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:16:21.812] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:10:0) after 1m0s
[12:17:21.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:10:0)
[12:17:21.846] Timer tick, asking Buildbucket for the build status
[12:17:21.908] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:17:21.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:11:0) after 1m0s
[12:18:21.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:11:0)
[12:18:21.925] Timer tick, asking Buildbucket for the build status
[12:18:22.058] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:18:22.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:12:0) after 1m0s
[12:19:22.051] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:12:0)
[12:19:22.051] Timer tick, asking Buildbucket for the build status
[12:19:22.135] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:19:22.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:13:0) after 1m0s
[12:20:22.176] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:13:0)
[12:20:22.176] Timer tick, asking Buildbucket for the build status
[12:20:22.236] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:20:22.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:14:0) after 1m0s
[12:21:22.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:14:0)
[12:21:22.278] Timer tick, asking Buildbucket for the build status
[12:21:22.365] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:21:22.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:15:0) after 1m0s
[12:22:22.425] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:15:0)
[12:22:22.425] Timer tick, asking Buildbucket for the build status
[12:22:22.496] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:22.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:16:0) after 1m0s
[12:23:22.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:16:0)
[12:23:22.506] Timer tick, asking Buildbucket for the build status
[12:23:22.570] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:22.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:17:0) after 1m0s
[12:24:22.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:17:0)
[12:24:22.598] Timer tick, asking Buildbucket for the build status
[12:24:22.675] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:22.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:18:0) after 1m0s
[12:25:22.695] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:18:0)
[12:25:22.695] Timer tick, asking Buildbucket for the build status
[12:25:22.997] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:22.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:19:0) after 1m0s
[12:26:23.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:19:0)
[12:26:23.013] Timer tick, asking Buildbucket for the build status
[12:26:23.097] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:23.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:20:0) after 1m0s
[12:27:23.140] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:20:0)
[12:27:23.140] Timer tick, asking Buildbucket for the build status
[12:27:23.453] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:23.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:21:0) after 1m0s
[12:28:23.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:21:0)
[12:28:23.471] Timer tick, asking Buildbucket for the build status
[12:28:23.558] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:23.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:22:0) after 1m0s
[12:29:23.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:22:0)
[12:29:23.602] Timer tick, asking Buildbucket for the build status
[12:29:23.728] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:23.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:23:0) after 1m0s
[12:30:23.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:23:0)
[12:30:23.807] Timer tick, asking Buildbucket for the build status
[12:30:23.914] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:23.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:24:0) after 1m0s
[12:31:23.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:24:0)
[12:31:23.979] Timer tick, asking Buildbucket for the build status
[12:31:24.073] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:24.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:25:0) after 1m0s
[12:32:24.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:25:0)
[12:32:24.127] Timer tick, asking Buildbucket for the build status
[12:32:24.196] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:24.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:26:0) after 1m0s
[12:33:24.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:26:0)
[12:33:24.220] Timer tick, asking Buildbucket for the build status
[12:33:24.307] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:24.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:27:0) after 1m0s
[12:34:24.398] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:27:0)
[12:34:24.398] Timer tick, asking Buildbucket for the build status
[12:34:24.505] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:24.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:28:0) after 1m0s
[12:35:24.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:28:0)
[12:35:24.485] Timer tick, asking Buildbucket for the build status
[12:35:24.582] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:24.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:29:0) after 1m0s
[12:36:24.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:29:0)
[12:36:24.621] Timer tick, asking Buildbucket for the build status
[12:36:24.898] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:24.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:30:0) after 1m0s
[12:37:24.971] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:30:0)
[12:37:24.971] Timer tick, asking Buildbucket for the build status
[12:37:25.055] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:25.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:31:0) after 1m0s
[12:38:25.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:31:0)
[12:38:25.100] Timer tick, asking Buildbucket for the build status
[12:38:25.253] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:25.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:32:0) after 1m0s
[12:39:25.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:32:0)
[12:39:25.273] Timer tick, asking Buildbucket for the build status
[12:39:25.362] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:25.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:33:0) after 1m0s
[12:40:25.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:33:0)
[12:40:25.396] Timer tick, asking Buildbucket for the build status
[12:40:25.575] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:25.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:34:0) after 1m0s
[12:41:25.569] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:34:0)
[12:41:25.569] Timer tick, asking Buildbucket for the build status
[12:41:25.639] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:25.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:35:0) after 1m0s
[12:42:25.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:35:0)
[12:42:25.714] Timer tick, asking Buildbucket for the build status
[12:42:25.796] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:25.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:36:0) after 1m0s
[12:43:25.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:36:0)
[12:43:25.830] Timer tick, asking Buildbucket for the build status
[12:43:26.142] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:26.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:37:0) after 1m0s
[12:44:26.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:37:0)
[12:44:26.146] Timer tick, asking Buildbucket for the build status
[12:44:26.289] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:26.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:38:0) after 1m0s
[12:45:26.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:38:0)
[12:45:26.317] Timer tick, asking Buildbucket for the build status
[12:45:26.797] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:26.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:39:0) after 1m0s
[12:46:26.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:39:0)
[12:46:26.818] Timer tick, asking Buildbucket for the build status
[12:46:26.930] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:26.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:40:0) after 1m0s
[12:47:27.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:40:0)
[12:47:27.035] Timer tick, asking Buildbucket for the build status
[12:47:27.111] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:27.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:41:0) after 1m0s
[12:48:27.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:41:0)
[12:48:27.152] Timer tick, asking Buildbucket for the build status
[12:48:27.244] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:27.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:42:0) after 1m0s
[12:49:27.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:42:0)
[12:49:27.285] Timer tick, asking Buildbucket for the build status
[12:49:27.389] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:27.389] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:43:0) after 1m0s
[12:50:27.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:43:0)
[12:50:27.498] Timer tick, asking Buildbucket for the build status
[12:50:27.599] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:27.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:44:0) after 1m0s
[12:51:27.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:44:0)
[12:51:27.621] Timer tick, asking Buildbucket for the build status
[12:51:27.845] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:27.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:45:0) after 1m0s
[12:52:27.951] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:45:0)
[12:52:27.951] Timer tick, asking Buildbucket for the build status
[12:52:28.265] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:28.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:46:0) after 1m0s
[12:53:28.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:46:0)
[12:53:28.363] Timer tick, asking Buildbucket for the build status
[12:53:28.458] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:28.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:47:0) after 1m0s
[12:54:28.541] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:47:0)
[12:54:28.541] Timer tick, asking Buildbucket for the build status
[12:54:28.630] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:28.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:48:0) after 1m0s
[12:55:28.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:48:0)
[12:55:28.681] Timer tick, asking Buildbucket for the build status
[12:55:28.765] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:28.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:49:0) after 1m0s
[12:56:28.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:49:0)
[12:56:28.766] Timer tick, asking Buildbucket for the build status
[12:56:28.844] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:28.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:50:0) after 1m0s
[12:57:30.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:50:0)
[12:57:30.184] Timer tick, asking Buildbucket for the build status
[12:57:30.283] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:30.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:51:0) after 1m0s
[12:58:30.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:51:0)
[12:58:30.454] Timer tick, asking Buildbucket for the build status
[12:58:30.831] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:30.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:52:0) after 1m0s
[12:59:30.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:52:0)
[12:59:30.929] Timer tick, asking Buildbucket for the build status
[12:59:31.021] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:31.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:53:0) after 1m0s
[13:00:31.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:53:0)
[13:00:31.123] Timer tick, asking Buildbucket for the build status
[13:00:31.530] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:31.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:54:0) after 1m0s
[13:01:31.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:54:0)
[13:01:31.704] Timer tick, asking Buildbucket for the build status
[13:01:31.959] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:31.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:55:0) after 1m0s
[13:02:32.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:55:0)
[13:02:32.035] Timer tick, asking Buildbucket for the build status
[13:02:32.106] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:32.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:56:0) after 1m0s
[13:03:32.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:56:0)
[13:03:32.180] Timer tick, asking Buildbucket for the build status
[13:03:32.255] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:32.255] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:57:0) after 1m0s
[13:04:32.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:57:0)
[13:04:32.314] Timer tick, asking Buildbucket for the build status
[13:04:32.412] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:32.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:58:0) after 1m0s
[13:05:32.514] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:58:0)
[13:05:32.515] Timer tick, asking Buildbucket for the build status
[13:05:32.584] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:32.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:59:0) after 1m0s
[13:06:32.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:59:0)
[13:06:32.624] Timer tick, asking Buildbucket for the build status
[13:06:32.701] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:32.701] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:60:0) after 1m0s
[13:07:32.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:60:0)
[13:07:32.784] Timer tick, asking Buildbucket for the build status
[13:07:32.875] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:32.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:61:0) after 1m0s
[13:08:32.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:61:0)
[13:08:32.943] Timer tick, asking Buildbucket for the build status
[13:08:33.199] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:33.199] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:62:0) after 1m0s
[13:09:33.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:62:0)
[13:09:33.285] Timer tick, asking Buildbucket for the build status
[13:09:33.436] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:33.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:63:0) after 1m0s
[13:10:34.349] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:63:0)
[13:10:34.349] Timer tick, asking Buildbucket for the build status
[13:10:34.440] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:34.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:64:0) after 1m0s
[13:11:34.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:64:0)
[13:11:34.469] Timer tick, asking Buildbucket for the build status
[13:11:34.569] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:34.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:65:0) after 1m0s
[13:12:34.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:65:0)
[13:12:34.604] Timer tick, asking Buildbucket for the build status
[13:12:34.672] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:34.672] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:66:0) after 1m0s
[13:13:34.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:66:0)
[13:13:34.749] Timer tick, asking Buildbucket for the build status
[13:13:34.822] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:34.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:67:0) after 1m0s
[13:14:34.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:67:0)
[13:14:34.932] Timer tick, asking Buildbucket for the build status
[13:14:35.008] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:35.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:68:0) after 1m0s
[13:15:35.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:68:0)
[13:15:35.180] Timer tick, asking Buildbucket for the build status
[13:15:35.285] Build 8853056349926767552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:35.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018486411927878736:69:0) after 1m0s
[13:16:17.849] Received PubSub notification, asking Buildbucket for the build status
[13:16:18.010] Build 8853056349926767552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:16:18.010] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615468575050356",
"created_by": "project:chromium",
"created_ts": "1615464559585864",
"id": "8853056349926767552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"c3fb27225edefafd9cb94fb66c3134b247e6ace7\"}}",
"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\": \"c3fb27225edefafd9cb94fb66c3134b247e6ace7\", \"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-p2d6\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17754, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"44b9579cf7b8d35cac13f9e2d70c57791ddfd0e2\", \"got_dawn_revision\": \"6d4617a2d28d8d3d4aa946abdbabb368d025b6b7\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"c3fb27225edefafd9cb94fb66c3134b247e6ace7\", \"got_revision_cp\": \"refs/heads/master@{#861941}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"a2fe8e1b2111e69d8846991f9d9bcc5c49505d1c\", \"got_v8_revision\": \"9c91874d93f2ca4e7df23a7abdb80bb888afb20b\", \"got_v8_revision_cp\": \"refs/heads/9.1.98@{#1}\", \"got_webrtc_revision\": \"92d12707e00837a978485f731d72ef30cf19d2a2\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33402}\", \"is_cached\": true, \"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\": \"c3fb27225edefafd9cb94fb66c3134b247e6ace7\", \"swarm_hashes_refs/heads/master(at){#861941}_without_patch\": {\"absl_hardening_tests\": \"c5bd3982c766e5f2bd1b610c6cacbfd1cd3d7819\", \"accessibility_unittests\": \"0ff9247c350ad69095079118f697ffdb1dd642f6\", \"angle_unittests\": \"3c1dc6256b9d7fccbd7c1bc1ba8b509cf8221361\", \"app_shell_unittests\": \"382e3455bd626f6006f28d242971bc4999306eeb\", \"aura_unittests\": \"aff8b75bfa232a8d987744c3d5a653d91208da78\", \"base_unittests\": \"1efe83d03d23e2e7de859939ff6d6e08611bacf2\", \"base_util_unittests\": \"b926b1645e8c2eeb265c7b5ebebae7087845f634\", \"blink_common_unittests\": \"aec5bf0972f85a0678b2749eac96585ac42595d9\", \"blink_fuzzer_unittests\": \"a7fb7374ac4cea37c28e2b5d2140dbd9f0f0bd67\", \"blink_heap_unittests\": \"d170bfcff09a037cf4b78287f4951aedf78bd397\", \"blink_platform_unittests\": \"4b930fcac6f2852c5d6af4bdd727d737f858ea8d\", \"blink_unittests\": \"eaf31205a905516a451c36d681799c692cdc40f2\", \"boringssl_crypto_tests\": \"9a1f0d05a5f2da44ba1d44ce34607fa6a86e4164\", \"boringssl_ssl_tests\": \"fa85440f16f14103d683a5b9feb78f1a17cf7e96\", \"browser_switcher_bho_unittests\": \"14466ce10db3d0cb42978c0fc21fd13dbecb7e0d\", \"browser_tests\": \"1c01fc12bc7e7d941e5294214bcb33a52394d12e\", \"capture_unittests\": \"a9a63a24f99a0dc48b00e526a300a36a8a1839a8\", \"cast_unittests\": \"a939aa0c4e92b2b62b61f2bc7dc4a31035427abf\", \"cc_unittests\": \"21747ef83d2880c2476d5bb4cc9581a4969953df\", \"chrome_app_unittests\": \"1cc3f5ed4f60716d4baae7c40071cf6078b3bf41\", \"chrome_cleaner_unittests\": \"bb5f5c7d4411e7be0be4475848c7d6d003d53c5d\", \"chrome_elf_unittests\": \"8734614a2924f36c5419e389a95f23a4697967fe\", \"chromedriver_unittests\": \"27c9f20d6a652e76ffe70f38f45bac02658f9152\", \"color_unittests\": \"479f2ac140b4dd0b618c3eae1eeae3aead673188\", \"components_browsertests\": \"04d9063c525f010b774a6fd23b45701fc7bdaa35\", \"components_unittests\": \"6b084db1ffdfc649536ceeb8288b66d011cfc824\", \"compositor_unittests\": \"7193481d67567b574914232109af66121ddc2353\", \"content_browsertests\": \"33472025884ec11383265c31d6aca8c939cf3051\", \"content_unittests\": \"f5dd1333efcb7be97085d3d23c530cd45ecbd831\", \"courgette_unittests\": \"fa03b48b02b2658a47a58181a65e9bf5f9e2f091\", \"crashpad_tests\": \"6f2f4d5b4c888beabe632c18e4f3280c41b6fea6\", \"cronet_tests\": \"dd418f61bf71545976c3dfd837f97235d07edcbd\", \"cronet_unittests\": \"82c272229677ab7f6facb756e7df9b355a6030ce\", \"crypto_unittests\": \"74a042f18ecc258beecd422df64217ff11e3aeb8\", \"delayloads_unittests\": \"1ecb412735081731c248f372ca50cf9cff11fd83\", \"device_unittests\": \"22bab6d183849af88abd88bcc49bc1a81c7b2f84\", \"display_unittests\": \"70688af76a8856c68c45fa9e7cad3762ca2dbc22\", \"elevation_service_unittests\": \"d80126e59333dd94f150b9605e035c325832bf21\", \"events_unittests\": \"de901e3b1c6b09dcb9b58eb66f49353c5410e196\", \"extensions_browsertests\": \"67179c19cc0c38b795335203124da60c79d1c904\", \"extensions_unittests\": \"d920dc14a86e20c43189c1af4003aba20c55e988\", \"filesystem_service_unittests\": \"ee3edd72161be70ddff11b0d7b597ef1b1b36c24\", \"gcm_unit_tests\": \"3cc00d81eab7767fe382af7b882da191d58bb11a\", \"gcp_unittests\": \"0dbe5a1a51a87c82bb1eba1bf39b79cf818041c5\", \"gfx_unittests\": \"a63e91769dfb02135c43186bbf0af5c901c42031\", \"gin_unittests\": \"02aa1204494fe881d22cee14b2a57b2b7e117270\", \"google_apis_unittests\": \"0b9aeca1d5b67744bd810ffbe23397d07e2d17e5\", \"gpu_unittests\": \"fda82a950952f951f61302345666f3f0e89582e1\", \"gwp_asan_unittests\": \"780d8498b32139dfbac882c6b8ff0c34919172dc\", \"headless_browsertests\": \"26e9020ab46015a73fbe246c21cc166f146234b7\", \"headless_unittests\": \"eb57e4b44f7a2e0156d3e90fa3965a8753d9fcae\", \"install_static_unittests\": \"e540142987a3a7daaa12667ae20694ed6f48d8aa\", \"installer_util_unittests\": \"dc495f508a176acfb198969eb591558758f3cb79\", \"interactive_ui_tests\": \"ed0c876f70bd3ae18d4f272278eb42a38ff19b02\", \"ipc_tests\": \"1975676be1271ecb8ba2248de28ffd26723a3bca\", \"jingle_unittests\": \"d1f1008bc69060468b1b63d789876a38c0048779\", \"latency_unittests\": \"e2617d9f73184c51df84355f2c268f8f236380f8\", \"libjingle_xmpp_unittests\": \"b48b3487a3838ea8ff3f619d955cf926eadf1f6d\", \"liburlpattern_unittests\": \"105f1f503be249e6ab83a74e3693811ee6372182\", \"media_blink_unittests\": \"6f531961af846b0d504a6bd89bc5c2c1b0672385\", \"media_unittests\": \"63ebbbca9c85fb1daf15f69f2e121625db35a7d4\", \"message_center_unittests\": \"6de4cc80d200de110518e648d88f44a8d400a83b\", \"midi_unittests\": \"f6f5e2677e2b8a2c1f5e8180963d1cf73dda5570\", \"mojo_core_unittests\": \"5c7d3b07bf94a2e33d18d92ac9604618b171ee8c\", \"mojo_unittests\": \"890bf56c015090a0d49273d8d2ccea551a404b4a\", \"nacl_loader_unittests\": \"ae705b5e461e7bf4549516d260dfa421ad7ad933\", \"native_theme_unittests\": \"b6a4b73b8e19f72d7ef4736be3c2bb5afe690bc8\", \"net_unittests\": \"99961bc3bdac0b4f8d5db12fd547a0fb9836c390\", \"notification_helper_unittests\": \"30c84e8ec8d4d067533f2a90c58ef804755b0cc3\", \"pdf_unittests\": \"c85c924bc16d4b11042ff30bfa01a43731515516\", \"perfetto_unittests\": \"1edfa2e69f72e4a72859b8c704294616b863f656\", \"ppapi_unittests\": \"60b12d69366a84455a2e6ed04e9c1b87aea82ca4\", \"printing_unittests\": \"07b250315d1c6ad7dbcea956686935f0fcf17fd7\", \"remoting_unittests\": \"7f7469b9cc9cceebe53441f640345ec53352c6c8\", \"sbox_integration_tests\": \"f2e1696dc4882ebf4ec8123aae6ae9d49705ca17\", \"sbox_unittests\": \"66103a542d74f4eb2426f9a911d957532198e9b3\", \"sbox_validation_tests\": \"36b87ce292be7ae88c122c216311e6ae9cff8825\", \"service_manager_unittests\": \"3f63eb10ed63bbba4e414b645c95704758952a9a\", \"services_unittests\": \"8f39668d3180329545cf8c297beb9acd3545614e\", \"setup_unittests\": \"81706200a89dd8838d9f802ae8d1f5becb4e4a0f\", \"shell_dialogs_unittests\": \"d107ceccd1a3b7402af81af7cc8cf8f3ca6dddf0\", \"skia_unittests\": \"0db666be99443ae1465003fff55597df140e4088\", \"snapshot_unittests\": \"af5120d4dd61c33670ca6fff78fd05e02bb578de\", \"sql_unittests\": \"a3f3428b4db3dbdde0a6bccaefb0315862707849\", \"storage_unittests\": \"4df9338c8fe908d133f0153807b2922ade4061ba\", \"sync_integration_tests\": \"5211960176af765e57675591da5593515579cf1f\", \"traffic_annotation_auditor_unittests\": \"a9846228d35c06001b9fe772f8db249e514e5937\", \"ui_base_unittests\": \"93118a34ce11fdbdc02b9f93d3a18a1cf7f377e4\", \"ui_touch_selection_unittests\": \"4367806053280468203d1fa9609af9f3c88997ba\", \"unit_tests\": \"248dc96a26b419e7251bd6dd0fa7075c900145d6\", \"updater_tests\": \"da6dfdef7c6de996e574a8e014c90755f7ee63a8\", \"url_unittests\": \"68b5aa51fd4927eec888162e5a11a62abab7f805\", \"views_unittests\": \"26bafa99682bb6320718a815237a999a2843f2c1\", \"viz_unittests\": \"202a874903f7696485f7be515f42b76dc748fcc5\", \"vr_common_unittests\": \"ddf8b45cd2afc828d80a786912f13ab59522c727\", \"vr_pixeltests\": \"a01422cee42e304b3f1f729a54d5af003d7af9ea\", \"weblayer_browsertests\": \"da95514269c7d93937a9aa2d85dd55562a608ec8\", \"weblayer_unittests\": \"9e01950818f9ac640d24394f5f6e3078d30bd621\", \"wm_unittests\": \"ab0811811d48ce01bbedcc5691ed8e012b738ef7\", \"wtf_unittests\": \"361fcd03919fa0ad1b2236c3886e81a05dbf354f\", \"zlib_unittests\": \"1ee2684bbdffb12bb6c0a2e989ed877f1608087c\", \"zucchini_unittests\": \"a05dfb991a0d86edd0ee140f46f8917338381f0b\"}}, \"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-p2d6\"], \"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\": [\"5754-8c4f760\"], \"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": "1615464561021228",
"status": "COMPLETED",
"status_changed_ts": "1615468575952728",
"tags": [
"build_address:luci.chromium.ci/win-asan/17754",
"builder:win-asan",
"buildset:commit/git/c3fb27225edefafd9cb94fb66c3134b247e6ace7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/c3fb27225edefafd9cb94fb66c3134b247e6ace7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018486411927878736",
"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/8853056349926767552/+/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:523a027f9c22bf10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615468576015044",
"url": "https://ci.chromium.org/b/8853056349926767552",
"utcnow_ts": "1615468577998538"
}
}
[13:16:18.010] Invocation finished in 1h6m59.717293305s with status SUCCEEDED