[14:25:33.034] New invocation is queued and will start shortly
[14:25:34.188] Starting the invocation (attempt 1)
[14:25:34.228] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:25:34.228] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:25:34.228] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026993954952224464",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"67870a5b3437cbab2a425caee09310a43254808a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026993954952224464",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/67870a5b3437cbab2a425caee09310a43254808a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/67870a5b3437cbab2a425caee09310a43254808a",
"gitiles_ref:refs/heads/master"
]
}
[14:25:35.040] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607351134365158",
"id": "8861563892890045040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"67870a5b3437cbab2a425caee09310a43254808a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607351134760510",
"tags": [
"build_address:luci.chromium.ci/win-asan/16083",
"builder:win-asan",
"buildset:commit/git/67870a5b3437cbab2a425caee09310a43254808a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/67870a5b3437cbab2a425caee09310a43254808a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026993954952224464",
"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/8861563892890045040/+/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": "1607351134760616",
"url": "https://ci.chromium.org/b/8861563892890045040",
"utcnow_ts": "1607351135030193"
}
}
[14:25:35.040] Task URL: https://ci.chromium.org/b/8861563892890045040
[14:25:35.040] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:2:0) after 1m0s
[14:25:48.900] Received PubSub notification, asking Buildbucket for the build status
[14:25:48.995] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:35.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:2:0)
[14:26:35.077] Timer tick, asking Buildbucket for the build status
[14:26:35.146] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:35.146] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:4:0) after 1m0s
[14:27:35.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:4:0)
[14:27:35.132] Timer tick, asking Buildbucket for the build status
[14:27:35.220] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:35.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:5:0) after 1m0s
[14:28:35.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:5:0)
[14:28:35.239] Timer tick, asking Buildbucket for the build status
[14:28:35.541] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:35.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:6:0) after 1m0s
[14:29:35.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:6:0)
[14:29:35.565] Timer tick, asking Buildbucket for the build status
[14:29:35.696] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:35.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:7:0) after 1m0s
[14:30:35.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:7:0)
[14:30:35.770] Timer tick, asking Buildbucket for the build status
[14:30:36.165] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:36.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:8:0) after 1m0s
[14:31:36.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:8:0)
[14:31:36.271] Timer tick, asking Buildbucket for the build status
[14:31:36.382] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:36.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:9:0) after 1m0s
[14:32:36.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:9:0)
[14:32:36.433] Timer tick, asking Buildbucket for the build status
[14:32:36.506] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:36.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:10:0) after 1m0s
[14:33:36.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:10:0)
[14:33:36.568] Timer tick, asking Buildbucket for the build status
[14:33:36.853] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:36.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:11:0) after 1m0s
[14:34:36.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:11:0)
[14:34:36.917] Timer tick, asking Buildbucket for the build status
[14:34:37.200] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:37.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:12:0) after 1m0s
[14:35:37.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:12:0)
[14:35:37.284] Timer tick, asking Buildbucket for the build status
[14:35:37.353] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:37.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:13:0) after 1m0s
[14:36:37.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:13:0)
[14:36:37.399] Timer tick, asking Buildbucket for the build status
[14:36:37.502] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:37.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:14:0) after 1m0s
[14:37:37.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:14:0)
[14:37:37.535] Timer tick, asking Buildbucket for the build status
[14:37:37.600] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:37:37.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:15:0) after 1m0s
[14:38:37.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:15:0)
[14:38:37.618] Timer tick, asking Buildbucket for the build status
[14:38:37.689] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:37.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:16:0) after 1m0s
[14:39:37.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:16:0)
[14:39:37.744] Timer tick, asking Buildbucket for the build status
[14:39:38.339] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:38.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:17:0) after 1m0s
[14:40:38.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:17:0)
[14:40:38.403] Timer tick, asking Buildbucket for the build status
[14:40:38.644] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:38.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:18:0) after 1m0s
[14:41:38.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:18:0)
[14:41:38.664] Timer tick, asking Buildbucket for the build status
[14:41:38.822] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:38.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:19:0) after 1m0s
[14:42:38.912] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:19:0)
[14:42:38.912] Timer tick, asking Buildbucket for the build status
[14:42:39.051] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:39.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:20:0) after 1m0s
[14:43:39.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:20:0)
[14:43:39.075] Timer tick, asking Buildbucket for the build status
[14:43:39.480] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:39.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:21:0) after 1m0s
[14:44:39.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:21:0)
[14:44:39.565] Timer tick, asking Buildbucket for the build status
[14:44:39.787] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:39.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:22:0) after 1m0s
[14:45:39.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:22:0)
[14:45:39.790] Timer tick, asking Buildbucket for the build status
[14:45:40.137] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:40.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:23:0) after 1m0s
[14:46:40.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:23:0)
[14:46:40.167] Timer tick, asking Buildbucket for the build status
[14:46:40.279] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:40.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:24:0) after 1m0s
[14:47:40.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:24:0)
[14:47:40.334] Timer tick, asking Buildbucket for the build status
[14:47:40.480] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:40.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:25:0) after 1m0s
[14:48:40.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:25:0)
[14:48:40.552] Timer tick, asking Buildbucket for the build status
[14:48:40.656] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:40.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:26:0) after 1m0s
[14:49:40.725] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:26:0)
[14:49:40.725] Timer tick, asking Buildbucket for the build status
[14:49:43.942] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:43.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:27:0) after 1m0s
[14:50:44.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:27:0)
[14:50:44.264] Timer tick, asking Buildbucket for the build status
[14:50:44.629] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:44.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:28:0) after 1m0s
[14:51:44.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:28:0)
[14:51:44.709] Timer tick, asking Buildbucket for the build status
[14:51:45.348] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:45.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:29:0) after 1m0s
[14:52:45.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:29:0)
[14:52:45.404] Timer tick, asking Buildbucket for the build status
[14:52:46.041] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:46.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:30:0) after 1m0s
[14:53:46.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:30:0)
[14:53:46.135] Timer tick, asking Buildbucket for the build status
[14:53:46.256] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:46.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:31:0) after 1m0s
[14:54:46.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:31:0)
[14:54:46.381] Timer tick, asking Buildbucket for the build status
[14:54:48.005] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:48.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:32:0) after 1m0s
[14:55:48.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:32:0)
[14:55:48.144] Timer tick, asking Buildbucket for the build status
[14:55:48.257] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:48.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:33:0) after 1m0s
[14:56:48.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:33:0)
[14:56:48.386] Timer tick, asking Buildbucket for the build status
[14:56:48.474] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:48.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:34:0) after 1m0s
[14:57:48.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:34:0)
[14:57:48.595] Timer tick, asking Buildbucket for the build status
[14:57:48.658] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:48.658] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:35:0) after 1m0s
[14:58:48.871] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:35:0)
[14:58:48.871] Timer tick, asking Buildbucket for the build status
[14:58:48.942] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:48.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:36:0) after 1m0s
[14:59:49.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:36:0)
[14:59:49.002] Timer tick, asking Buildbucket for the build status
[14:59:49.137] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:49.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:37:0) after 1m0s
[15:00:49.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:37:0)
[15:00:49.262] Timer tick, asking Buildbucket for the build status
[15:00:49.453] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:49.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:38:0) after 1m0s
[15:01:49.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:38:0)
[15:01:49.540] Timer tick, asking Buildbucket for the build status
[15:01:49.624] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:49.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:39:0) after 1m0s
[15:02:49.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:39:0)
[15:02:49.848] Timer tick, asking Buildbucket for the build status
[15:02:50.120] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:50.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:40:0) after 1m0s
[15:03:50.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:40:0)
[15:03:50.177] Timer tick, asking Buildbucket for the build status
[15:03:50.332] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:50.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:41:0) after 1m0s
[15:04:50.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:41:0)
[15:04:50.371] Timer tick, asking Buildbucket for the build status
[15:04:50.568] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:50.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:42:0) after 1m0s
[15:05:50.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:42:0)
[15:05:50.624] Timer tick, asking Buildbucket for the build status
[15:05:50.731] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:50.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:43:0) after 1m0s
[15:06:50.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:43:0)
[15:06:50.841] Timer tick, asking Buildbucket for the build status
[15:06:51.248] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:51.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:44:0) after 1m0s
[15:07:51.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:44:0)
[15:07:51.304] Timer tick, asking Buildbucket for the build status
[15:07:51.464] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:51.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:45:0) after 1m0s
[15:08:52.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:45:0)
[15:08:52.027] Timer tick, asking Buildbucket for the build status
[15:08:52.141] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:52.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:46:0) after 1m0s
[15:09:52.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:46:0)
[15:09:52.411] Timer tick, asking Buildbucket for the build status
[15:09:52.687] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:52.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:47:0) after 1m0s
[15:10:52.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:47:0)
[15:10:52.795] Timer tick, asking Buildbucket for the build status
[15:10:52.917] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:52.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:48:0) after 1m0s
[15:11:53.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:48:0)
[15:11:53.111] Timer tick, asking Buildbucket for the build status
[15:11:53.399] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:53.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:49:0) after 1m0s
[15:12:53.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:49:0)
[15:12:53.656] Timer tick, asking Buildbucket for the build status
[15:12:54.013] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:54.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:50:0) after 1m0s
[15:13:54.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:50:0)
[15:13:54.089] Timer tick, asking Buildbucket for the build status
[15:13:54.298] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:54.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:51:0) after 1m0s
[15:14:54.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:51:0)
[15:14:54.314] Timer tick, asking Buildbucket for the build status
[15:14:54.447] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:54.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:52:0) after 1m0s
[15:15:54.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:52:0)
[15:15:54.526] Timer tick, asking Buildbucket for the build status
[15:15:54.719] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:54.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:53:0) after 1m0s
[15:16:54.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:53:0)
[15:16:54.811] Timer tick, asking Buildbucket for the build status
[15:16:55.233] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:55.233] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:54:0) after 1m0s
[15:17:55.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:54:0)
[15:17:55.327] Timer tick, asking Buildbucket for the build status
[15:17:55.417] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:55.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:55:0) after 1m0s
[15:18:55.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:55:0)
[15:18:55.507] Timer tick, asking Buildbucket for the build status
[15:18:55.600] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:55.600] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:56:0) after 1m0s
[15:19:55.637] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:56:0)
[15:19:55.637] Timer tick, asking Buildbucket for the build status
[15:19:55.720] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:55.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:57:0) after 1m0s
[15:20:55.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:57:0)
[15:20:55.769] Timer tick, asking Buildbucket for the build status
[15:20:55.896] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:55.896] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:58:0) after 1m0s
[15:21:55.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:58:0)
[15:21:55.881] Timer tick, asking Buildbucket for the build status
[15:21:55.958] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:55.958] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:59:0) after 1m0s
[15:22:55.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:59:0)
[15:22:55.985] Timer tick, asking Buildbucket for the build status
[15:22:56.130] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:56.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:60:0) after 1m0s
[15:23:56.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:60:0)
[15:23:56.154] Timer tick, asking Buildbucket for the build status
[15:23:56.314] Build 8861563892890045040: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:56.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026993954952224464:61:0) after 1m0s
[15:24:06.961] Received PubSub notification, asking Buildbucket for the build status
[15:24:07.317] Build 8861563892890045040: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:24:07.317] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607354645813575",
"created_by": "project:chromium",
"created_ts": "1607351134365158",
"id": "8861563892890045040",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"67870a5b3437cbab2a425caee09310a43254808a\"}}",
"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}, \"$kitchen/crbug_1150511_keys_with_int_value\": [\"$build/goma.jobs\", \"$recipe_engine/buildbucket.build.infra.swarming.priority\", \"$recipe_engine/buildbucket.build.number\", \"buildnumber\"], \"$recipe_engine/isolated\": {\"server\": \"https://isolateserver.appspot.com\"}, \"$recipe_engine/milo/blamelist_pins\": [{\"host\": \"chromium.googlesource.com\", \"id\": \"67870a5b3437cbab2a425caee09310a43254808a\", \"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-5-jjte\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16083, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"cf023b3a8cd1fdeba958849517eee411023c6ae8\", \"got_dawn_revision\": \"d63d562d1b26e01b369d9a58eddeaccfaa688a02\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"67870a5b3437cbab2a425caee09310a43254808a\", \"got_revision_cp\": \"refs/heads/master@{#834194}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7c84426d2abe231f3087a2942fd831137eb3b0aa\", \"got_v8_revision\": \"61f3e61408129afc0d9e1268d3354a283ea3c881\", \"got_v8_revision_cp\": \"refs/heads/8.9.100@{#1}\", \"got_webrtc_revision\": \"393159147685c9294e423eb8b2bdc92dc78acfd4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32780}\", \"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\": \"67870a5b3437cbab2a425caee09310a43254808a\", \"swarm_hashes_refs/heads/master(at){#834194}_without_patch\": {\"absl_hardening_tests\": \"63b88e47a41aef16fb3b54f1ec423142ea619109\", \"accessibility_unittests\": \"73848565232c56b542f3edd319ca9bf020ff80f6\", \"angle_unittests\": \"1b8227ede498fa04f458e613f51223a4157c094c\", \"app_shell_unittests\": \"a22e5ba5bd8a38f76f08250d682ea71fbfbe2af1\", \"aura_unittests\": \"975fa807bf4ae15a7d63d666f669d7ae5ac9ec9a\", \"base_unittests\": \"a166249ffe67c5e243e25c6bd2cd6c65c0578d46\", \"base_util_unittests\": \"ee1007b0310e1d7a7d450e897d23c0509c1dc35f\", \"blink_common_unittests\": \"b2114182dfc547cd2bf9242fb99945fecffb3695\", \"blink_fuzzer_unittests\": \"b531ef044c8e3db58ec4923b81bcf74331e3c824\", \"blink_heap_unittests\": \"8e586c222a88892a1dae555f9316b6a2c1a89916\", \"blink_platform_unittests\": \"75585313f4b76792f5c4ef1ae773bf94e6346992\", \"blink_unittests\": \"3ac442fe160f4bc5e25f78e35696d2f31707461f\", \"boringssl_crypto_tests\": \"a0f8698ef186532823d1dd3381c6d2b123021d70\", \"boringssl_ssl_tests\": \"19b72fec7146a8d603e1712e8987fe2dd02c6532\", \"browser_switcher_bho_unittests\": \"fa6bd8fce8ea8b115da662775aed98df39b88ecb\", \"browser_tests\": \"ccec4c1b361e8faccb1a319d723807ef0398fbe7\", \"capture_unittests\": \"2aea697d90110e1faf5752329c56e4a72d07675b\", \"cast_unittests\": \"ca88f3d7c7fe9f7b55d00a2d05b8254f4f52439a\", \"cc_unittests\": \"86c2c7976968d4573f89a81ef2dcd738fdf6387b\", \"chrome_app_unittests\": \"c8c2d8681d3f941495d6a99e83fb7514ea9d57ef\", \"chrome_cleaner_unittests\": \"98a72280b2c86fcae7f4f3e4799b7df6da6bf965\", \"chrome_elf_unittests\": \"e259385bb54a887f6c9efbacf045708aa227fc1f\", \"chromedriver_unittests\": \"0f02559149a0c0d9256ae2b84071e569acd1f134\", \"components_browsertests\": \"966e9fd036fa8c9ad876b1780276b9c3e8c42877\", \"components_unittests\": \"16102d9f7c3d3467a9390fe3b34e4331bb128dea\", \"compositor_unittests\": \"d317cd049d8ce31fac7a413c1acb45ccab49211c\", \"content_browsertests\": \"c20c99b49a1c3b780f401256a5ca3316fcda4731\", \"content_unittests\": \"7b37139d35bb4139b940f7d043e6e796f22fcafc\", \"courgette_unittests\": \"e95abef4ba108bc671fba3d252e82ba918b79e00\", \"crashpad_tests\": \"1f9e2ee0c35f4ba90f330c5ef4eec0cb39ae964a\", \"cronet_tests\": \"431c34b1998bcef7902dc7c5337d9f14afaaef0d\", \"cronet_unittests\": \"25f2bcbd2efdcd651552bd49cc95cc9ed91dd097\", \"crypto_unittests\": \"a616fe905aa27cbdaf80e43ad62fab27218178dc\", \"delayloads_unittests\": \"c30fa1b73454ea1b953dcba376d2b79fada7cf2c\", \"device_unittests\": \"e9d7ed074af827b187a658c395379bd529bd94de\", \"display_unittests\": \"1f0def3bd2af985d2c91f5fc1308627badc03351\", \"elevation_service_unittests\": \"f4e1338dfe501ddf478456cae9f909ab74bc3ec8\", \"events_unittests\": \"85e0b072ebf50d7fbc836740a9a60c71cda541d0\", \"extensions_browsertests\": \"9b96d5861e336629ce0a93b225530e5d800389a9\", \"extensions_unittests\": \"4ecd849fbacaf005542e5ce1197db413a90f0ded\", \"filesystem_service_unittests\": \"4325536f2f44086d28c4243aede2beb0a6c5a2a8\", \"gcm_unit_tests\": \"537328ce3323ac04f8072528cd82fb549b52b2d1\", \"gcp_unittests\": \"20a36822bba26910f8f4eb44ff5a9cb10e970b3c\", \"gfx_unittests\": \"81d869ce296ea40e452057b6c725d349c2e0f3b4\", \"gin_unittests\": \"7381882d18e043a717560ea17f9eeb84ccf4daf2\", \"google_apis_unittests\": \"eda56b42ccd6672ca72502af423522f278080a2a\", \"gpu_unittests\": \"2561afff3c5c021a77e69514896515a3f674d008\", \"gwp_asan_unittests\": \"4bcbf76086391f6012fdb52948e13ba7e1bd2ec6\", \"headless_browsertests\": \"b591b94c67e93056df86eba4fe57f3aee62cb55f\", \"headless_unittests\": \"72e0bb40a1c6d1b6c44fda4042e1cf01b509c86b\", \"install_static_unittests\": \"f697c4406258ee404aa02defa69325e848d17674\", \"installer_util_unittests\": \"152a564197ba0d22902b1f17d86906d54bbd76ca\", \"interactive_ui_tests\": \"b2e8d95106d5f8c4f25ae34279ece9607e742e43\", \"ipc_tests\": \"f1bd2b6d6d456325638255eb2bda6eb8028e19f9\", \"jingle_unittests\": \"055c5fdf4e1c64a30ab28111949021b908d2e75a\", \"latency_unittests\": \"b4b0eaba17a4632e05a4a11a22beb02db17d9aa5\", \"libjingle_xmpp_unittests\": \"af27b529a8175df7d580602dbf68717a7f36d070\", \"liburlpattern_unittests\": \"c187beb945f1623cad21b6f4b1deedd4544242fa\", \"media_blink_unittests\": \"5ad7cf09e66cb563a06ffa33b8a273e0374e54e2\", \"media_unittests\": \"8b1c225e22a89cdcb68c2e99b833336630c31c6b\", \"message_center_unittests\": \"67ebd13e4dbe3c2c62b74c4ddbc89c768e31fe4a\", \"midi_unittests\": \"554fd07d8976f5734b49e9d9ae94f478b0b88bdf\", \"mojo_core_unittests\": \"b01643ef32e9da5d27ad7979042229d1c6c8bc29\", \"mojo_unittests\": \"c46479d16892fd11a87df3c3dbe17e158eaa1acc\", \"nacl_loader_unittests\": \"baf8ab749e04d8f85de1af5d3d3a5940b91a980c\", \"native_theme_unittests\": \"c6b4607e59fb5f9bece6388ce1e303cee2a5b077\", \"net_unittests\": \"61c4d201634f5f79c1c856f2fef1555ef7e71c19\", \"notification_helper_unittests\": \"2c30028f2d060dca26feaf8d0b9b34c09811a680\", \"pdf_unittests\": \"250d81a5290bb15c7216fe7f694a236872b39b36\", \"perfetto_unittests\": \"7ab59422735d83b418c7f019e1770108758cacc8\", \"ppapi_unittests\": \"f15add2072696fa8e9c1c11a8b73ffbc1715f8af\", \"printing_unittests\": \"ed76c4b047e1d7b07cc3cb1f1ac19d9c1ac71fb7\", \"remoting_unittests\": \"0245f08a87500197467043ac82ae594e79cc86a1\", \"sbox_integration_tests\": \"035d7be5c0e3ee8ce135ffce6803e49aa00428c4\", \"sbox_unittests\": \"9b6c01b85dbb681c892a38400d841c6bee964736\", \"sbox_validation_tests\": \"9933970aec975ca4d6bcfe39ef5fdd27941987d0\", \"service_manager_unittests\": \"d5692ad7b40c1c2e800bb753cd6a6a606cde2d9d\", \"services_unittests\": \"3ea1aa7e8064e48d6bf74b47c38f6e35d700a2df\", \"setup_unittests\": \"4fb621b5a2604962b38afd1180f063aac5a5c60f\", \"shell_dialogs_unittests\": \"c8fa03d1ab5f36e07eb4c6373cb83a1e33eca737\", \"skia_unittests\": \"2e2e3a3953d0e48c316b20ced04b0e7afed2e10c\", \"snapshot_unittests\": \"6e505b4027ba8db4e99cf243f5b92454cc65208e\", \"sql_unittests\": \"cafaf138e9466008cad181ddcef514221a375aa9\", \"storage_unittests\": \"4ff93b7510ecfbca1474a9c47d3719fef3f0dace\", \"sync_integration_tests\": \"94bed62f4461fd0b91c375af75b86e63295b489e\", \"traffic_annotation_auditor_unittests\": \"4c988aee3b12ab430dd5dc2d387306d927705c33\", \"ui_base_unittests\": \"cb2d3d50facfaee601a75b8bfe531e7294b74727\", \"ui_touch_selection_unittests\": \"9231caaa8db4e66e0bb4e673536bb38f2188f3ea\", \"unit_tests\": \"c4cf3013cb03b9e24749d8a4042248d3da700b79\", \"updater_tests\": \"bfa9a6a91f38d1fc822d62e8ba8eb8e1a6a5bacf\", \"url_unittests\": \"ec4e7a2764c0594249dfad9217c17e3bc5f3b92e\", \"views_unittests\": \"b5d18863de55bec6aa7c2a45986842928b5c7806\", \"viz_unittests\": \"1808c80e1a5f333611e9e7808dfda7e5f0d2ea2e\", \"vr_common_unittests\": \"0af60ee12222ff9b18edfcfafcfcd1831c612809\", \"vr_pixeltests\": \"0abef3ec86c2ebf33922a84711e79546c01607ec\", \"weblayer_browsertests\": \"8b36c81b47178e54500ef5dad71499c9dd9a6549\", \"weblayer_unittests\": \"a08e7d32e9739ba669b6cd03872f44dbcfd59c52\", \"wm_unittests\": \"55f411da418dc7eebfe0343f6f493d981037bfeb\", \"wtf_unittests\": \"d6d9c2ab8ade2b58b8fe27295acf6b2cd45734ec\", \"zlib_unittests\": \"c753ab8197d7fc888eb625aebb91a3c2d46d8de8\", \"zucchini_unittests\": \"322a0ad0de1e8477566d96132a7a9d92eef6f279\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-jjte\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5640-d0eb544\"], \"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": "1607351148283349",
"status": "COMPLETED",
"status_changed_ts": "1607354646308664",
"tags": [
"build_address:luci.chromium.ci/win-asan/16083",
"builder:win-asan",
"buildset:commit/git/67870a5b3437cbab2a425caee09310a43254808a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/67870a5b3437cbab2a425caee09310a43254808a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026993954952224464",
"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/8861563892890045040/+/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:5056697382df1610",
"user_agent:luci-scheduler"
],
"updated_ts": "1607354646318753",
"url": "https://ci.chromium.org/b/8861563892890045040",
"utcnow_ts": "1607354647302614"
}
}
[15:24:07.317] Invocation finished in 58m34.30134282s with status SUCCEEDED