[17:33:04.676] New invocation is queued and will start shortly
[17:33:05.778] Starting the invocation (attempt 1)
[17:33:05.848] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:33:05.848] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:33:05.849] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026166784052888560",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"862f8ec295d5f048ac89af825347494fcb28613c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026166784052888560",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/862f8ec295d5f048ac89af825347494fcb28613c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/862f8ec295d5f048ac89af825347494fcb28613c",
"gitiles_ref:refs/heads/master"
]
}
[17:33:07.026] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1608139986024405",
"id": "8860736721973595104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"862f8ec295d5f048ac89af825347494fcb28613c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608139986790791",
"tags": [
"build_address:luci.chromium.ci/win-asan/16264",
"builder:win-asan",
"buildset:commit/git/862f8ec295d5f048ac89af825347494fcb28613c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/862f8ec295d5f048ac89af825347494fcb28613c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026166784052888560",
"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/8860736721973595104/+/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": "1608139986790975",
"url": "https://ci.chromium.org/b/8860736721973595104",
"utcnow_ts": "1608139987016353"
}
}
[17:33:07.026] Task URL: https://ci.chromium.org/b/8860736721973595104
[17:33:07.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:2:0) after 1m0s
[17:33:10.542] Received PubSub notification, asking Buildbucket for the build status
[17:33:10.624] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:07.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:2:0)
[17:34:07.048] Timer tick, asking Buildbucket for the build status
[17:34:07.160] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:07.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:4:0) after 1m0s
[17:35:07.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:4:0)
[17:35:07.231] Timer tick, asking Buildbucket for the build status
[17:35:07.427] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:07.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:5:0) after 1m0s
[17:36:07.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:5:0)
[17:36:07.467] Timer tick, asking Buildbucket for the build status
[17:36:07.617] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:07.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:6:0) after 1m0s
[17:37:07.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:6:0)
[17:37:07.786] Timer tick, asking Buildbucket for the build status
[17:37:07.960] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:07.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:7:0) after 1m0s
[17:38:07.952] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:7:0)
[17:38:07.952] Timer tick, asking Buildbucket for the build status
[17:38:08.931] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:08.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:8:0) after 1m0s
[17:39:09.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:8:0)
[17:39:09.118] Timer tick, asking Buildbucket for the build status
[17:39:09.213] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:09.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:9:0) after 1m0s
[17:40:09.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:9:0)
[17:40:09.284] Timer tick, asking Buildbucket for the build status
[17:40:09.373] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:09.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:10:0) after 1m0s
[17:41:09.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:10:0)
[17:41:09.409] Timer tick, asking Buildbucket for the build status
[17:41:09.814] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:09.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:11:0) after 1m0s
[17:42:09.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:11:0)
[17:42:09.872] Timer tick, asking Buildbucket for the build status
[17:42:09.931] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:09.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:12:0) after 1m0s
[17:43:10.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:12:0)
[17:43:10.027] Timer tick, asking Buildbucket for the build status
[17:43:10.126] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:10.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:13:0) after 1m0s
[17:44:10.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:13:0)
[17:44:10.184] Timer tick, asking Buildbucket for the build status
[17:44:10.312] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:10.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:14:0) after 1m0s
[17:45:10.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:14:0)
[17:45:10.379] Timer tick, asking Buildbucket for the build status
[17:45:10.642] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:10.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:15:0) after 1m0s
[17:46:10.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:15:0)
[17:46:10.736] Timer tick, asking Buildbucket for the build status
[17:46:10.825] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:10.825] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:16:0) after 1m0s
[17:47:10.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:16:0)
[17:47:10.946] Timer tick, asking Buildbucket for the build status
[17:47:11.081] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:11.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:17:0) after 1m0s
[17:48:11.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:17:0)
[17:48:11.249] Timer tick, asking Buildbucket for the build status
[17:48:11.362] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:11.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:18:0) after 1m0s
[17:49:11.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:18:0)
[17:49:11.496] Timer tick, asking Buildbucket for the build status
[17:49:11.648] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:11.648] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:19:0) after 1m0s
[17:50:11.663] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:19:0)
[17:50:11.663] Timer tick, asking Buildbucket for the build status
[17:50:13.370] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:13.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:20:0) after 1m0s
[17:51:13.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:20:0)
[17:51:13.403] Timer tick, asking Buildbucket for the build status
[17:51:13.706] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:13.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:21:0) after 1m0s
[17:52:13.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:21:0)
[17:52:13.785] Timer tick, asking Buildbucket for the build status
[17:52:13.969] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:13.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:22:0) after 1m0s
[17:53:14.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:22:0)
[17:53:14.087] Timer tick, asking Buildbucket for the build status
[17:53:14.512] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:14.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:23:0) after 1m0s
[17:54:14.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:23:0)
[17:54:14.657] Timer tick, asking Buildbucket for the build status
[17:54:14.762] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:14.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:24:0) after 1m0s
[17:55:14.791] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:24:0)
[17:55:14.791] Timer tick, asking Buildbucket for the build status
[17:55:14.970] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:14.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:25:0) after 1m0s
[17:56:15.040] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:25:0)
[17:56:15.040] Timer tick, asking Buildbucket for the build status
[17:56:15.926] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:15.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:26:0) after 1m0s
[17:57:15.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:26:0)
[17:57:15.961] Timer tick, asking Buildbucket for the build status
[17:57:16.070] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:16.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:27:0) after 1m0s
[17:58:16.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:27:0)
[17:58:16.161] Timer tick, asking Buildbucket for the build status
[17:58:16.316] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:16.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:28:0) after 1m0s
[17:59:16.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:28:0)
[17:59:16.309] Timer tick, asking Buildbucket for the build status
[17:59:16.628] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:16.628] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:29:0) after 1m0s
[18:00:16.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:29:0)
[18:00:16.854] Timer tick, asking Buildbucket for the build status
[18:00:17.015] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:17.015] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:30:0) after 1m0s
[18:01:17.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:30:0)
[18:01:17.188] Timer tick, asking Buildbucket for the build status
[18:01:17.326] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:17.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:31:0) after 1m0s
[18:02:17.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:31:0)
[18:02:17.455] Timer tick, asking Buildbucket for the build status
[18:02:17.622] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:17.622] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:32:0) after 1m0s
[18:03:17.729] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:32:0)
[18:03:17.729] Timer tick, asking Buildbucket for the build status
[18:03:17.870] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:17.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:33:0) after 1m0s
[18:04:17.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:33:0)
[18:04:17.855] Timer tick, asking Buildbucket for the build status
[18:04:18.111] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:18.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:34:0) after 1m0s
[18:05:18.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:34:0)
[18:05:18.119] Timer tick, asking Buildbucket for the build status
[18:05:18.365] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:18.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:35:0) after 1m0s
[18:06:18.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:35:0)
[18:06:18.396] Timer tick, asking Buildbucket for the build status
[18:06:18.527] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:18.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:36:0) after 1m0s
[18:07:18.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:36:0)
[18:07:18.597] Timer tick, asking Buildbucket for the build status
[18:07:18.763] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:18.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:37:0) after 1m0s
[18:08:18.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:37:0)
[18:08:18.915] Timer tick, asking Buildbucket for the build status
[18:08:19.384] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:19.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:38:0) after 1m0s
[18:09:19.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:38:0)
[18:09:19.446] Timer tick, asking Buildbucket for the build status
[18:09:19.518] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:19.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:39:0) after 1m0s
[18:10:19.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:39:0)
[18:10:19.591] Timer tick, asking Buildbucket for the build status
[18:10:19.683] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:19.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:40:0) after 1m0s
[18:11:19.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:40:0)
[18:11:19.714] Timer tick, asking Buildbucket for the build status
[18:11:19.839] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:19.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:41:0) after 1m0s
[18:12:19.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:41:0)
[18:12:19.928] Timer tick, asking Buildbucket for the build status
[18:12:20.042] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:20.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:42:0) after 1m0s
[18:13:20.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:42:0)
[18:13:20.156] Timer tick, asking Buildbucket for the build status
[18:13:20.303] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:20.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:43:0) after 1m0s
[18:14:20.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:43:0)
[18:14:20.380] Timer tick, asking Buildbucket for the build status
[18:14:20.542] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:20.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:44:0) after 1m0s
[18:15:20.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:44:0)
[18:15:20.600] Timer tick, asking Buildbucket for the build status
[18:15:21.149] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:21.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:45:0) after 1m0s
[18:16:21.167] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:45:0)
[18:16:21.167] Timer tick, asking Buildbucket for the build status
[18:16:21.313] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:21.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:46:0) after 1m0s
[18:17:21.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:46:0)
[18:17:21.353] Timer tick, asking Buildbucket for the build status
[18:17:21.530] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:21.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:47:0) after 1m0s
[18:18:21.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:47:0)
[18:18:21.556] Timer tick, asking Buildbucket for the build status
[18:18:21.700] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:21.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:48:0) after 1m0s
[18:19:21.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:48:0)
[18:19:21.765] Timer tick, asking Buildbucket for the build status
[18:19:22.246] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:22.246] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:49:0) after 1m0s
[18:20:22.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:49:0)
[18:20:22.343] Timer tick, asking Buildbucket for the build status
[18:20:22.541] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:20:22.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:50:0) after 1m0s
[18:21:22.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:50:0)
[18:21:22.628] Timer tick, asking Buildbucket for the build status
[18:21:23.860] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:21:23.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:51:0) after 1m0s
[18:22:24.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:51:0)
[18:22:24.042] Timer tick, asking Buildbucket for the build status
[18:22:24.193] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:22:24.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:52:0) after 1m0s
[18:23:24.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:52:0)
[18:23:24.296] Timer tick, asking Buildbucket for the build status
[18:23:24.433] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:23:24.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:53:0) after 1m0s
[18:24:24.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:53:0)
[18:24:24.471] Timer tick, asking Buildbucket for the build status
[18:24:24.567] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:24:24.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:54:0) after 1m0s
[18:25:24.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:54:0)
[18:25:24.600] Timer tick, asking Buildbucket for the build status
[18:25:24.715] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:25:24.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:55:0) after 1m0s
[18:26:24.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:55:0)
[18:26:24.765] Timer tick, asking Buildbucket for the build status
[18:26:24.878] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:26:24.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:56:0) after 1m0s
[18:27:24.908] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:56:0)
[18:27:24.908] Timer tick, asking Buildbucket for the build status
[18:27:25.319] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:27:25.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:57:0) after 1m0s
[18:28:25.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:57:0)
[18:28:25.362] Timer tick, asking Buildbucket for the build status
[18:28:25.496] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:28:25.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:58:0) after 1m0s
[18:29:25.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:58:0)
[18:29:25.592] Timer tick, asking Buildbucket for the build status
[18:29:25.684] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:29:25.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:59:0) after 1m0s
[18:30:25.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:59:0)
[18:30:25.823] Timer tick, asking Buildbucket for the build status
[18:30:25.920] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:30:25.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:60:0) after 1m0s
[18:31:26.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:60:0)
[18:31:26.108] Timer tick, asking Buildbucket for the build status
[18:31:26.372] Build 8860736721973595104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:31:26.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026166784052888560:61:0) after 1m0s
[18:32:08.958] Received PubSub notification, asking Buildbucket for the build status
[18:32:09.072] Build 8860736721973595104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:32:09.072] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1608143528313925",
"created_by": "project:chromium",
"created_ts": "1608139986024405",
"id": "8860736721973595104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"862f8ec295d5f048ac89af825347494fcb28613c\"}}",
"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\": \"862f8ec295d5f048ac89af825347494fcb28613c\", \"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-us-central1-b-32-1-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16264, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"c47b951ecbcbbba653db801bc7eb5f4fa5d72042\", \"got_dawn_revision\": \"455dbfdd428fd9d4730af7afa9dbfd6301513971\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"862f8ec295d5f048ac89af825347494fcb28613c\", \"got_revision_cp\": \"refs/heads/master@{#837618}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1ca6504eb7bb9971d317cf8c8033b38b914036b7\", \"got_v8_revision\": \"24e738a6bc98c6971154c655e17f656297085c45\", \"got_v8_revision_cp\": \"refs/heads/8.9.157@{#1}\", \"got_webrtc_revision\": \"cf8ec1f6867651204d81b14a8c31a86e6eb97572\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32829}\", \"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\": \"862f8ec295d5f048ac89af825347494fcb28613c\", \"swarm_hashes_refs/heads/master(at){#837618}_without_patch\": {\"absl_hardening_tests\": \"1286c7d58a1455ac423c88a4fb1b7b4345f9c99a\", \"accessibility_unittests\": \"09e4b98e5b3cc648f7e300c547f0ec4be45330b5\", \"angle_unittests\": \"8badf41c92b130f60042077257372c2b7e92a804\", \"app_shell_unittests\": \"f86505e6fc974b94a3d7956e31a4a745695ceac5\", \"aura_unittests\": \"237fb82dddddfd786fa96f75d97cb6e533a19be1\", \"base_unittests\": \"25c5551f54be4a3e97c2903ff9f2ff66e9b8c4c6\", \"base_util_unittests\": \"e7b4196d24dd360c6df8d7d072914412baac29e7\", \"blink_common_unittests\": \"d51d9d07cfaf041c73c2df339b46f1bf7681256a\", \"blink_fuzzer_unittests\": \"1612ed881059fdb33b491884d9d23c601d230dc5\", \"blink_heap_unittests\": \"07043901c07eb9989390ea52cc1464b292cb1979\", \"blink_platform_unittests\": \"11145115f362a9617e4ddf54c9c093e6c28afcea\", \"blink_unittests\": \"f92511140fe241253f82afea9f071f0a1b7bda70\", \"boringssl_crypto_tests\": \"103a0b3064aa7b5e4adee52b4d218921a32de3f9\", \"boringssl_ssl_tests\": \"f79fe60cd34f87fb549841438aa1d1b9f44064a8\", \"browser_switcher_bho_unittests\": \"d641433e32c97e29016d16022d88f93bd54cff5e\", \"browser_tests\": \"3d21a0d6ccc32cf58134efb9fd5ccdc922fab4a6\", \"capture_unittests\": \"8e40e246c3639acae92188d5bba4842302c8bf22\", \"cast_unittests\": \"4d8e50305eb687e3c101229a941efb8cbb458e48\", \"cc_unittests\": \"b665acdfb875026453ff06e4e95483a2f746f7fc\", \"chrome_app_unittests\": \"0d9a133014452b62b9b2a1a273fc4f1d639e2df8\", \"chrome_cleaner_unittests\": \"5b98e18656a963c994dbad256e9d6172df7a43df\", \"chrome_elf_unittests\": \"4b0217f054f11b500b2da485bdb2354fc722ff7d\", \"chromedriver_unittests\": \"e670ea89dd8c06958e4bfaeff4e965d06e1a4926\", \"components_browsertests\": \"5b20ca7d90b9fca20bfa60977f65a87fa3ac99b6\", \"components_unittests\": \"9e8d13688ca3bec1ae71abe6025a4c704d4059f3\", \"compositor_unittests\": \"1fa79f5f30f8fbc875c7dd0eadcbcbb81b110e5e\", \"content_browsertests\": \"58b498c78a8b3594312a932a4bd86bf8c6f0b9a1\", \"content_unittests\": \"ece11895fc5ed30eacc25a29b0659529c2c128c5\", \"courgette_unittests\": \"2964e51fbfe1058c06e07bea963c3353e5ef6297\", \"crashpad_tests\": \"66119c9b88a6d6ee4fb79be090a4df6995360c14\", \"cronet_tests\": \"31e14eea5fd2c099feee9d5e8a41badf8d1412d1\", \"cronet_unittests\": \"dea230555b2c7319c57c16c03929869153160b5f\", \"crypto_unittests\": \"ceaf94583e2c3d8c30a604873957a9fe1fbcef88\", \"delayloads_unittests\": \"a180d16016f4e30839f344f821f97c4ebe04aac2\", \"device_unittests\": \"819dadd0665ea8a971d046f24f35d19af3a280d6\", \"display_unittests\": \"640e7a42b4ffd6ea4fde090aac13ddb459fb1d3b\", \"elevation_service_unittests\": \"1229f633c5e240c56a5bf418551f0a58762c3911\", \"events_unittests\": \"7db8dcdaf98b7e85e6da37c1f1d358171447eac9\", \"extensions_browsertests\": \"a37f92ed957c90f6787ac01866094f44789856c0\", \"extensions_unittests\": \"53da8ccb742c93d7deb004f97b59875792c0e942\", \"filesystem_service_unittests\": \"dc425bb7371ee2333d1caba566e11f85cd91a602\", \"gcm_unit_tests\": \"0f365ef9896cd0c0226e53784425f46428927555\", \"gcp_unittests\": \"868880bc20cc66284a123f22ea60c136a6d475c7\", \"gfx_unittests\": \"d2c4d2dead0344e1d77767ebf1314a34759f0dd7\", \"gin_unittests\": \"904b2cbe25584464e050608e591f0c175ca8f3a7\", \"google_apis_unittests\": \"382d1ece5e267eeafb62972a8ec1f0e4afa69ffd\", \"gpu_unittests\": \"a8e7666f3a5d9d7eb3c2cd9d057d20afe367d191\", \"gwp_asan_unittests\": \"a1ab0145203ea6f9c51280f9639f9e41a9340bbd\", \"headless_browsertests\": \"00224060fb274265f239eecdf6413de70e8d81d1\", \"headless_unittests\": \"49d9ad802793b2e722d2ef2d67b4cf260de4b14d\", \"install_static_unittests\": \"ce3df1d6419b83963b709e384de902c5cde0f1b3\", \"installer_util_unittests\": \"8ffc7a80e8b99f3b41409f787c703f17e72d76a2\", \"interactive_ui_tests\": \"c95695da7ca3b520e005f080b3993966842f83aa\", \"ipc_tests\": \"d58e361c1bbe75e9757441720a56f340b1110fc8\", \"jingle_unittests\": \"0f78f8496e786ccb26bbbdc97cfd08d33d166138\", \"latency_unittests\": \"05bbcd2f874974539b9e93b4eda0d0fdc6327fb7\", \"libjingle_xmpp_unittests\": \"3291e867fe253c3709fdb5e549c1df3500d390ad\", \"liburlpattern_unittests\": \"765cdba24f323af782fb3eac15722f793d0c1616\", \"media_blink_unittests\": \"75f5cb3fc364447a8718f2982091a44f666bcee8\", \"media_unittests\": \"e9bdcf7f8b5e239bcb6ab8e177236b02d36eea09\", \"message_center_unittests\": \"708f9904594e5c6c3a5d3ccacedcd7d30fd94001\", \"midi_unittests\": \"dace6d2fa23a3c59353f394ad35d9cbf03060254\", \"mojo_core_unittests\": \"7d71c064393fc3e921d5fb2cf53217bd8b08ebef\", \"mojo_unittests\": \"877ad1fc51d35e19429cb91e9b144fede748ad7d\", \"nacl_loader_unittests\": \"3331f9a17c3ae71e652138a3729315f051e5983f\", \"native_theme_unittests\": \"71695123a62d06f3a221129ecdabee8e370a137b\", \"net_unittests\": \"98ecf220d5086dc3d32afb9c3c61d8bc4be5c339\", \"notification_helper_unittests\": \"c8ee0b4e0db4c57805e1e000a23fc7adabd73b7d\", \"pdf_unittests\": \"fc6e1c9da423ed85a73b6c953fbe7b49d231bf39\", \"perfetto_unittests\": \"08bd44787c9de33c07537c7404aa616af52b036a\", \"ppapi_unittests\": \"52c5120407e032267f5480ae777d58c2973c2e39\", \"printing_unittests\": \"36fe4c0a150e147ba1f8558210daddfe91cfe37b\", \"remoting_unittests\": \"937a95debe465c0c158011d585a9862af8ccbeeb\", \"sbox_integration_tests\": \"fb8bf70d7f6834a4c89b113e43b64667b9ca209e\", \"sbox_unittests\": \"8fbff56bf61fb2dd11b37937ee69dfc9458575d0\", \"sbox_validation_tests\": \"445e5674bf662bd1aa1e512d8a2b9f8debd6d82a\", \"service_manager_unittests\": \"d50ea61a44f24eed03e4d87d3e40b97756e4017d\", \"services_unittests\": \"a95aa957933f754e6afd65711dd2047223db3750\", \"setup_unittests\": \"1e817f8f76406623535992ad07134a4955a1191b\", \"shell_dialogs_unittests\": \"2cd880e22602df049ddebf78cb00c074a0505c7e\", \"skia_unittests\": \"6b281f2a62ded971883247cf344d2bd550531b53\", \"snapshot_unittests\": \"ae6c2568f0c7ca872df7582d2d57dcb0e18e8be1\", \"sql_unittests\": \"2f99ef6e6735edc1510754c836f79f2355edb0b1\", \"storage_unittests\": \"f54baaf62b6f80564834056b0334d842a756ba3e\", \"sync_integration_tests\": \"d48cd8642c14182886dd97d90ddb409f492f6892\", \"traffic_annotation_auditor_unittests\": \"95c6bbde0248eb56f22c6dfaf9d7844590e6b26c\", \"ui_base_unittests\": \"29c5f916f08a0041bad19cd177b7c809234d5801\", \"ui_touch_selection_unittests\": \"6e1847c44110642843c0771fc51a13dc6ef57ebe\", \"unit_tests\": \"d56f62ea3b17b9df7de81bae16544ac859b010b5\", \"updater_tests\": \"5a0e8d81e5a224bc4b23b2763ebf0c3545dc801f\", \"url_unittests\": \"7201d9c6370f4faefd22aef7ada40db0e54fc93d\", \"views_unittests\": \"a37e09a10c4f9822c7d2d4ca3263cc1ac37f1b6a\", \"viz_unittests\": \"52a168cc86a00fbc01f6ec9e75499b87d1d734bf\", \"vr_common_unittests\": \"7869c452489d876c1c60ec2ed6fb80bc8198787c\", \"vr_pixeltests\": \"597dbcdb658a57aae349e91e63e4ef0ec8852c27\", \"weblayer_browsertests\": \"af1fef5c5dfbe409b1cca089b8b586d85c649b8a\", \"weblayer_unittests\": \"688f91b68affb363fb0c4cb0d9cee3b106db833d\", \"wm_unittests\": \"024d982f6c92bbff008bc8bbc907bbfd61a2ab3c\", \"wtf_unittests\": \"a3f71f2b3f506bf03c5ba8cc379eb634de6f7995\", \"zlib_unittests\": \"8474e38654c87812f1daccf1967ba8a6fba4ca40\", \"zucchini_unittests\": \"d89a172d0319c154ba1684c0db9384361c6cedbc\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-qsbv\"], \"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\": [\"5657-0417a3f\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1608139988850701",
"status": "COMPLETED",
"status_changed_ts": "1608143528565827",
"tags": [
"build_address:luci.chromium.ci/win-asan/16264",
"builder:win-asan",
"buildset:commit/git/862f8ec295d5f048ac89af825347494fcb28613c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/862f8ec295d5f048ac89af825347494fcb28613c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026166784052888560",
"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/8860736721973595104/+/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:50856e61cf6ab910",
"user_agent:luci-scheduler"
],
"updated_ts": "1608143528569839",
"url": "https://ci.chromium.org/b/8860736721973595104",
"utcnow_ts": "1608143529049999"
}
}
[18:32:09.072] Invocation finished in 59m4.415036657s with status SUCCEEDED