[12:51:06.729] New invocation is queued and will start shortly
[12:51:07.925] Starting the invocation (attempt 1)
[12:51:07.969] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:51:07.969] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:51:07.969] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018755572557012656",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"69911d6e05f4777ae8cb04522d40ecf132ea9c55\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018755572557012656",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/69911d6e05f4777ae8cb04522d40ecf132ea9c55",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/69911d6e05f4777ae8cb04522d40ecf132ea9c55",
"gitiles_ref:refs/heads/master"
]
}
[12:51:08.867] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1615207868145067",
"id": "8853325510410437248",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"69911d6e05f4777ae8cb04522d40ecf132ea9c55\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615207868756697",
"tags": [
"build_address:luci.chromium.ci/win-asan/17702",
"builder:win-asan",
"buildset:commit/git/69911d6e05f4777ae8cb04522d40ecf132ea9c55",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/69911d6e05f4777ae8cb04522d40ecf132ea9c55",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018755572557012656",
"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/8853325510410437248/+/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": "1615207868756808",
"url": "https://ci.chromium.org/b/8853325510410437248",
"utcnow_ts": "1615207868861888"
}
}
[12:51:08.867] Task URL: https://ci.chromium.org/b/8853325510410437248
[12:51:08.867] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:2:0) after 1m0s
[12:51:22.306] Received PubSub notification, asking Buildbucket for the build status
[12:51:22.523] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:08.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:2:0)
[12:52:08.942] Timer tick, asking Buildbucket for the build status
[12:52:09.024] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:09.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:4:0) after 1m0s
[12:53:09.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:4:0)
[12:53:09.066] Timer tick, asking Buildbucket for the build status
[12:53:09.483] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:09.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:5:0) after 1m0s
[12:54:09.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:5:0)
[12:54:09.485] Timer tick, asking Buildbucket for the build status
[12:54:09.571] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:09.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:6:0) after 1m0s
[12:55:09.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:6:0)
[12:55:09.550] Timer tick, asking Buildbucket for the build status
[12:55:09.960] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:09.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:7:0) after 1m0s
[12:56:10.096] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:7:0)
[12:56:10.096] Timer tick, asking Buildbucket for the build status
[12:56:10.170] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:10.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:8:0) after 1m0s
[12:57:10.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:8:0)
[12:57:10.202] Timer tick, asking Buildbucket for the build status
[12:57:10.462] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:10.462] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:9:0) after 1m0s
[12:58:10.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:9:0)
[12:58:10.527] Timer tick, asking Buildbucket for the build status
[12:58:11.898] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:11.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:10:0) after 1m0s
[12:59:12.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:10:0)
[12:59:12.000] Timer tick, asking Buildbucket for the build status
[12:59:12.358] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:12.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:11:0) after 1m0s
[13:00:12.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:11:0)
[13:00:12.604] Timer tick, asking Buildbucket for the build status
[13:00:12.766] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:12.766] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:12:0) after 1m0s
[13:01:12.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:12:0)
[13:01:12.870] Timer tick, asking Buildbucket for the build status
[13:01:13.023] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:13.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:13:0) after 1m0s
[13:02:13.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:13:0)
[13:02:13.206] Timer tick, asking Buildbucket for the build status
[13:02:13.568] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:13.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:14:0) after 1m0s
[13:03:13.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:14:0)
[13:03:13.722] Timer tick, asking Buildbucket for the build status
[13:03:13.788] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:13.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:15:0) after 1m0s
[13:04:13.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:15:0)
[13:04:13.902] Timer tick, asking Buildbucket for the build status
[13:04:13.971] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:13.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:16:0) after 1m0s
[13:05:14.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:16:0)
[13:05:14.043] Timer tick, asking Buildbucket for the build status
[13:05:14.111] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:14.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:17:0) after 1m0s
[13:06:14.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:17:0)
[13:06:14.144] Timer tick, asking Buildbucket for the build status
[13:06:14.235] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:14.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:18:0) after 1m0s
[13:07:14.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:18:0)
[13:07:14.249] Timer tick, asking Buildbucket for the build status
[13:07:14.403] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:14.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:19:0) after 1m0s
[13:08:14.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:19:0)
[13:08:14.427] Timer tick, asking Buildbucket for the build status
[13:08:14.530] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:14.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:20:0) after 1m0s
[13:09:14.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:20:0)
[13:09:14.516] Timer tick, asking Buildbucket for the build status
[13:09:14.636] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:14.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:21:0) after 1m0s
[13:10:14.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:21:0)
[13:10:14.661] Timer tick, asking Buildbucket for the build status
[13:10:14.732] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:14.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:22:0) after 1m0s
[13:11:14.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:22:0)
[13:11:14.748] Timer tick, asking Buildbucket for the build status
[13:11:15.046] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:15.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:23:0) after 1m0s
[13:12:15.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:23:0)
[13:12:15.058] Timer tick, asking Buildbucket for the build status
[13:12:15.195] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:15.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:24:0) after 1m0s
[13:13:15.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:24:0)
[13:13:15.243] Timer tick, asking Buildbucket for the build status
[13:13:15.353] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:15.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:25:0) after 1m0s
[13:14:15.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:25:0)
[13:14:15.347] Timer tick, asking Buildbucket for the build status
[13:14:15.432] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:15.432] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:26:0) after 1m0s
[13:15:15.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:26:0)
[13:15:15.446] Timer tick, asking Buildbucket for the build status
[13:15:15.522] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:15.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:27:0) after 1m0s
[13:16:15.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:27:0)
[13:16:15.604] Timer tick, asking Buildbucket for the build status
[13:16:16.169] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:16.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:28:0) after 1m0s
[13:17:16.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:28:0)
[13:17:16.222] Timer tick, asking Buildbucket for the build status
[13:17:16.299] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:16.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:29:0) after 1m0s
[13:18:16.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:29:0)
[13:18:16.342] Timer tick, asking Buildbucket for the build status
[13:18:16.504] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:16.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:30:0) after 1m0s
[13:19:16.522] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:30:0)
[13:19:16.522] Timer tick, asking Buildbucket for the build status
[13:19:16.852] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:16.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:31:0) after 1m0s
[13:20:16.865] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:31:0)
[13:20:16.865] Timer tick, asking Buildbucket for the build status
[13:20:16.946] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:16.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:32:0) after 1m0s
[13:21:16.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:32:0)
[13:21:16.977] Timer tick, asking Buildbucket for the build status
[13:21:17.050] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:17.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:33:0) after 1m0s
[13:22:17.060] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:33:0)
[13:22:17.061] Timer tick, asking Buildbucket for the build status
[13:22:17.174] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:17.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:34:0) after 1m0s
[13:23:17.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:34:0)
[13:23:17.185] Timer tick, asking Buildbucket for the build status
[13:23:17.256] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:17.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:35:0) after 1m0s
[13:24:17.236] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:35:0)
[13:24:17.236] Timer tick, asking Buildbucket for the build status
[13:24:17.335] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:17.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:36:0) after 1m0s
[13:25:17.343] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:36:0)
[13:25:17.343] Timer tick, asking Buildbucket for the build status
[13:25:17.423] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:25:17.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:37:0) after 1m0s
[13:26:17.455] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:37:0)
[13:26:17.455] Timer tick, asking Buildbucket for the build status
[13:26:17.567] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:26:17.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:38:0) after 1m0s
[13:27:17.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:38:0)
[13:27:17.584] Timer tick, asking Buildbucket for the build status
[13:27:17.697] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:27:17.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:39:0) after 1m0s
[13:28:17.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:39:0)
[13:28:17.715] Timer tick, asking Buildbucket for the build status
[13:28:17.884] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:28:17.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:40:0) after 1m0s
[13:29:17.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:40:0)
[13:29:17.960] Timer tick, asking Buildbucket for the build status
[13:29:18.106] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:29:18.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:41:0) after 1m0s
[13:30:18.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:41:0)
[13:30:18.114] Timer tick, asking Buildbucket for the build status
[13:30:18.223] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:30:18.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:42:0) after 1m0s
[13:31:18.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:42:0)
[13:31:18.242] Timer tick, asking Buildbucket for the build status
[13:31:18.665] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:31:18.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:43:0) after 1m0s
[13:32:18.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:43:0)
[13:32:18.722] Timer tick, asking Buildbucket for the build status
[13:32:18.802] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:32:18.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:44:0) after 1m0s
[13:33:18.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:44:0)
[13:33:18.790] Timer tick, asking Buildbucket for the build status
[13:33:18.860] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:33:18.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:45:0) after 1m0s
[13:34:18.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:45:0)
[13:34:18.847] Timer tick, asking Buildbucket for the build status
[13:34:18.921] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:34:18.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:46:0) after 1m0s
[13:35:19.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:46:0)
[13:35:19.004] Timer tick, asking Buildbucket for the build status
[13:35:19.076] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:35:19.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:47:0) after 1m0s
[13:36:19.136] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:47:0)
[13:36:19.136] Timer tick, asking Buildbucket for the build status
[13:36:19.254] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:36:19.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:48:0) after 1m0s
[13:37:19.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:48:0)
[13:37:19.387] Timer tick, asking Buildbucket for the build status
[13:37:19.464] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:37:19.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:49:0) after 1m0s
[13:38:19.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:49:0)
[13:38:19.507] Timer tick, asking Buildbucket for the build status
[13:38:19.763] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:38:19.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:50:0) after 1m0s
[13:39:19.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:50:0)
[13:39:19.825] Timer tick, asking Buildbucket for the build status
[13:39:19.913] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:39:19.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:51:0) after 1m0s
[13:40:19.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:51:0)
[13:40:19.931] Timer tick, asking Buildbucket for the build status
[13:40:20.017] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:40:20.017] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:52:0) after 1m0s
[13:41:20.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:52:0)
[13:41:20.035] Timer tick, asking Buildbucket for the build status
[13:41:20.112] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:41:20.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:53:0) after 1m0s
[13:42:20.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:53:0)
[13:42:20.139] Timer tick, asking Buildbucket for the build status
[13:42:20.247] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:42:20.247] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:54:0) after 1m0s
[13:43:20.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:54:0)
[13:43:20.274] Timer tick, asking Buildbucket for the build status
[13:43:20.607] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:43:20.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:55:0) after 1m0s
[13:44:20.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:55:0)
[13:44:20.626] Timer tick, asking Buildbucket for the build status
[13:44:23.152] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:44:23.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:56:0) after 1m0s
[13:45:23.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:56:0)
[13:45:23.214] Timer tick, asking Buildbucket for the build status
[13:45:24.594] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:45:24.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:57:0) after 1m0s
[13:46:24.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:57:0)
[13:46:24.649] Timer tick, asking Buildbucket for the build status
[13:46:24.727] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:46:24.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:58:0) after 1m0s
[13:47:24.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:58:0)
[13:47:24.717] Timer tick, asking Buildbucket for the build status
[13:47:24.868] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:47:24.868] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:59:0) after 1m0s
[13:48:24.920] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:59:0)
[13:48:24.920] Timer tick, asking Buildbucket for the build status
[13:48:25.172] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:48:25.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:60:0) after 1m0s
[13:49:25.230] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:60:0)
[13:49:25.230] Timer tick, asking Buildbucket for the build status
[13:49:25.321] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:49:25.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:61:0) after 1m0s
[13:50:25.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:61:0)
[13:50:25.334] Timer tick, asking Buildbucket for the build status
[13:50:25.406] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:50:25.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:62:0) after 1m0s
[13:51:25.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:62:0)
[13:51:25.377] Timer tick, asking Buildbucket for the build status
[13:51:25.541] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:51:25.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:63:0) after 1m0s
[13:52:25.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:63:0)
[13:52:25.593] Timer tick, asking Buildbucket for the build status
[13:52:25.945] Build 8853325510410437248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:52:25.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018755572557012656:64:0) after 1m0s
[13:53:09.174] Received PubSub notification, asking Buildbucket for the build status
[13:53:09.332] Build 8853325510410437248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:53:09.332] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1615211587818604",
"created_by": "project:chromium",
"created_ts": "1615207868145067",
"id": "8853325510410437248",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"69911d6e05f4777ae8cb04522d40ecf132ea9c55\"}}",
"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\": \"69911d6e05f4777ae8cb04522d40ecf132ea9c55\", \"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-2xaw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17702, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-1\", \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"69911d6e05f4777ae8cb04522d40ecf132ea9c55\", \"got_revision_cp\": \"refs/heads/master@{#860691}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"f8b883fbba96b155409eb00da93826216bea9fc0\", \"got_v8_revision_cp\": \"refs/heads/9.1.60@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"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\": \"69911d6e05f4777ae8cb04522d40ecf132ea9c55\", \"swarm_hashes_refs/heads/master(at){#860691}_without_patch\": {\"absl_hardening_tests\": \"d4839ba9ba86aa9ffadb155ff50ff33ad1c9c273\", \"accessibility_unittests\": \"1d99ae5179bb3d24441ac202d01541759f0e0502\", \"angle_unittests\": \"75f9bc528c302ac620423c70f3150893ba7ed744\", \"app_shell_unittests\": \"0f20679181099ee45559cb73f717de41397c57c3\", \"aura_unittests\": \"77add4a63e93a727281385ff3b2fbb18829ef6cb\", \"base_unittests\": \"7d80f301d07762f0e925b08fd1c431fd476d1c77\", \"base_util_unittests\": \"6c7aff3a502fbe72fe994697271be85155c44887\", \"blink_common_unittests\": \"28fa1697b88ac7cab79b0ede4beb994536b164e2\", \"blink_fuzzer_unittests\": \"c2d8433c18125bc28c28c14d666d50dcf5b63581\", \"blink_heap_unittests\": \"5367325e280b802e539e7b1331d5f9cbbf15e13e\", \"blink_platform_unittests\": \"4b9b23bda5a2f4f7ad33d14be64012e05f7e48bc\", \"blink_unittests\": \"b49659491e60c2d55a727e979c30eaddfa656480\", \"boringssl_crypto_tests\": \"2a54773cc316a3fed584710b7273f5223b1da399\", \"boringssl_ssl_tests\": \"90202be014d41cfbac1d84ca0b7e1b6b14a02286\", \"browser_switcher_bho_unittests\": \"529d8dd3a3b0c3b32ee3c0de2b753ccd52096b5c\", \"browser_tests\": \"cce8029aa2d6e35628ff21e7b30ef9d335fed8f8\", \"capture_unittests\": \"128dadac194ba5488cdf31ad6a375d357888a569\", \"cast_unittests\": \"41ef050deba025c55b1688dfdd92bbf27777d5e7\", \"cc_unittests\": \"2b4d2b505715e189cae935d57da292eb3f1816cd\", \"chrome_app_unittests\": \"51ef89d32d6fb7882623c6ebfae31ef17895594a\", \"chrome_cleaner_unittests\": \"9c51146347a18af3988a3f1b631e5dff6727a70e\", \"chrome_elf_unittests\": \"16c726cc00970b68ce109ae17e33fa42f880e944\", \"chromedriver_unittests\": \"bd4ab03db5063e62808647fe02b04e0083d35ab8\", \"color_unittests\": \"93adf28313ebccea6d7c1bdc760ade24925841b5\", \"components_browsertests\": \"6919058b6065c31138c5b8790de7a3e071f95491\", \"components_unittests\": \"0d77407467f097503ee74ee3e8fcd2798543712e\", \"compositor_unittests\": \"c4c03d65eba58ae130d51028b51738bb35ae09b8\", \"content_browsertests\": \"ff5c97f07bd1bbac0c8ef0adbee533a28c685ab2\", \"content_unittests\": \"f2e92d8ca4c78e7b95aaf51038aebbec1ec5bd74\", \"courgette_unittests\": \"453de8095e9ebf6ab3ff0c4ae5c27ff16ce89cf2\", \"crashpad_tests\": \"9777fe37a2c0f60e8fe2d80b331cd74544c4c20b\", \"cronet_tests\": \"0f1bab4a7e27e32c69e0797ec4741a67242b9447\", \"cronet_unittests\": \"3781c68f70f6944a330965b606af3353f79d422a\", \"crypto_unittests\": \"d41e10803caa055afe2d8c898d999ad308dcdec7\", \"delayloads_unittests\": \"b3992fab940324466a5e5cd006b03ca1ff39e6fa\", \"device_unittests\": \"e59d14c0603f4a844fe2a3bb72d3bfee45b78817\", \"display_unittests\": \"9b4221ac5247896c3d3d06de9bfb9f2498ff0f11\", \"elevation_service_unittests\": \"984e4c548d6043cdf8cae2789592bc81f8ddfb4e\", \"events_unittests\": \"04e1dd1829a2e22977265e40e35355004dd91315\", \"extensions_browsertests\": \"0de4dd8a43f8afc6fe320e565ae05f6512fefbe4\", \"extensions_unittests\": \"39d22f9d78aa8397e8f767d631ccd1259bd0a18a\", \"filesystem_service_unittests\": \"d3222b0d2b8ebf89e1c9fde6dd1aaf6597604c32\", \"gcm_unit_tests\": \"a9e6bfbeb33266d6166ef26dc41db379f8b3a81b\", \"gcp_unittests\": \"312fc1d35d3fea44880b643210c4d1224d659150\", \"gfx_unittests\": \"292201f378f7b7feaa9d588c45c0a480eef21a09\", \"gin_unittests\": \"205708feeac91f3b9453147ffb2df669e3e5973d\", \"google_apis_unittests\": \"88ff0bd5fa0f678ac2209d2c78fd9ab6cb572c92\", \"gpu_unittests\": \"c59d15591b9363b5f392e30c0be3fdaf39c43a21\", \"gwp_asan_unittests\": \"91f6d5380ef34bca005ed20808e0c31e3ee771af\", \"headless_browsertests\": \"ca8fcf89507b3092459566d14d1908530a3725fd\", \"headless_unittests\": \"9218b32f728a1d2171f61df48edf443d38ae3b56\", \"install_static_unittests\": \"1a0c5695fc9b32889281a95cfc641ab1bf07d641\", \"installer_util_unittests\": \"04e728c967b118871b592592c2d8856e05148692\", \"interactive_ui_tests\": \"0a1e5d8d750bcb52301bb248f8cc7621928a486b\", \"ipc_tests\": \"067a52d165efca269b360fbe1bd8b1e740ea8811\", \"jingle_unittests\": \"b4267e7d57d3155adab40accfedbcbd2d131d73b\", \"latency_unittests\": \"0cd31ed5b708694f9dbeb55a8566ba6cb049a44e\", \"libjingle_xmpp_unittests\": \"c456698b7c143598953d02e550b1bbb47f3452b3\", \"liburlpattern_unittests\": \"e0603e4eabf5056a04ccb5fc4cfe148534046ecb\", \"media_blink_unittests\": \"ffefd03ee722eac1b1b62a054f60577247fa4c7d\", \"media_unittests\": \"6c1e43b198a6bd0acbf99e275c777da47f55870c\", \"message_center_unittests\": \"f9ca9266980bf34d504ace50b9bb0a37eadf0524\", \"midi_unittests\": \"703fbe32c825fbd92a5921c434be721151dfe4b9\", \"mojo_core_unittests\": \"0195c8fe41db7da16fe5f27046763f6a17dcc7d3\", \"mojo_unittests\": \"da26838afd1c9c5a0d2ddbfb85dd610d14e6e631\", \"nacl_loader_unittests\": \"e7074b2a33c8d1e19b0670d18f44273bfb6b889a\", \"native_theme_unittests\": \"51250239068e4b8facc0cdb6e953e4de269b0afb\", \"net_unittests\": \"6a03498d68f9946d26acd039fdaa4cb8e90a02fd\", \"notification_helper_unittests\": \"ea758253f81080a1fcfaa383e0d89f168832e101\", \"pdf_unittests\": \"7b3cee763caa8bfb33af68d19fe5137ec470b65f\", \"perfetto_unittests\": \"87cadc504bb74f94c5d3fb2b874e8ea3b0eaf945\", \"ppapi_unittests\": \"2e6e2104d39a188c740a7e826765e0d0939ab3b3\", \"printing_unittests\": \"1486e04d7f515ddcf53c5c9b691b62dc306bdd1e\", \"remoting_unittests\": \"1d3806cc47a033cb8107dd9446e73c09be204cd7\", \"sbox_integration_tests\": \"0c22f9887979fc514e1b4e953b33701666b241de\", \"sbox_unittests\": \"020e1ba2a12d67758e03088ad717d51a469d5c86\", \"sbox_validation_tests\": \"768e021832eaa38bba256529e61321f040e11ac7\", \"service_manager_unittests\": \"31f843094bd8c2ddbf00aec98db771c645455a58\", \"services_unittests\": \"13fe11e0f845959a474a342b1e85131bfb693609\", \"setup_unittests\": \"ab1646167e405b17d490e73efc81a9fefa733297\", \"shell_dialogs_unittests\": \"1547dcdb74e0f1373a87b2e39696c5c18520fb5d\", \"skia_unittests\": \"26aa25f77f0dee811a4fc872c28cd72fa8466ea7\", \"snapshot_unittests\": \"7be6004739c96f26fa808d872327bc3cefb62a72\", \"sql_unittests\": \"7ea02dd40ad27d26abe661d9fbe41056d238e2c4\", \"storage_unittests\": \"3cbb223d6d1a102973925dab33c9a191eeb90c39\", \"sync_integration_tests\": \"6d600d4077730c4848005f429f0d78b3a30bd0a5\", \"traffic_annotation_auditor_unittests\": \"c1b3f37593321a6d777be72a9b71f0554fd2df29\", \"ui_base_unittests\": \"c4295055af8e5257f6a69955fc10fd7d2d2fce25\", \"ui_touch_selection_unittests\": \"9f034f6d41ada4ec775d3e476ea959c983b984b4\", \"unit_tests\": \"b37a463107dc080d05c77cb7aab43304de276caa\", \"updater_tests\": \"d52ed1098a7039442140fce924244b0cfd6ab08e\", \"url_unittests\": \"ae5c8f19552eca2df39f13252b6b62ebcb138fc7\", \"views_unittests\": \"d1d3000f80fe76d57b4a2ab9efaf2ae92125ac6b\", \"viz_unittests\": \"06f24a05d2d8f43d6609fbdef23e9649f10a6096\", \"vr_common_unittests\": \"1c70a5471c3fc338f62bac90f1a3b4bb8a7bce4b\", \"vr_pixeltests\": \"268de8903567564a54906064b2659edce1fc5d67\", \"weblayer_browsertests\": \"fceed0a429fe5cf9da18ad94af93f9d2421f0699\", \"weblayer_unittests\": \"22e12885ff1c46e2f86b5cf766b762d2b36d3462\", \"wm_unittests\": \"8656bb1fab53be61a3d73b19fb87321b7264e75e\", \"wtf_unittests\": \"7df49b0c41a90af4f709b6ad88a0081949cac1a5\", \"zlib_unittests\": \"ee0bbbcd7952f3ab36bc94006a1536ca4e741575\", \"zucchini_unittests\": \"245349eaafcab3ca1afee1bfc7607b23fc73e1ac\"}}, \"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-2xaw\"], \"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\": [\"5744-5c0a4b4\"], \"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": "1615207881339964",
"status": "COMPLETED",
"status_changed_ts": "1615211588741289",
"tags": [
"build_address:luci.chromium.ci/win-asan/17702",
"builder:win-asan",
"buildset:commit/git/69911d6e05f4777ae8cb04522d40ecf132ea9c55",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/69911d6e05f4777ae8cb04522d40ecf132ea9c55",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018755572557012656",
"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/8853325510410437248/+/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:522ab5b28ebd5410",
"user_agent:luci-scheduler"
],
"updated_ts": "1615211588747141",
"url": "https://ci.chromium.org/b/8853325510410437248",
"utcnow_ts": "1615211589322887"
}
}
[13:53:09.332] Invocation finished in 1h2m2.618105691s with status SUCCEEDED