[01:04:29.409] New invocation is queued and will start shortly
[01:04:31.521] Starting the invocation (attempt 1)
[01:04:31.564] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:04:31.564] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:04:31.564] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030034053205999024",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"60c3fa895f4b80ec0c07f165cd488159c2ff10b9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030034053205999024",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/60c3fa895f4b80ec0c07f165cd488159c2ff10b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/60c3fa895f4b80ec0c07f165cd488159c2ff10b9",
"gitiles_ref:refs/heads/master"
]
}
[01:04:32.270] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604451871736640",
"id": "8864603990100918336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"60c3fa895f4b80ec0c07f165cd488159c2ff10b9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604451872114402",
"tags": [
"build_address:luci.chromium.ci/win-asan/15444",
"builder:win-asan",
"buildset:commit/git/60c3fa895f4b80ec0c07f165cd488159c2ff10b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/60c3fa895f4b80ec0c07f165cd488159c2ff10b9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030034053205999024",
"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/8864603990100918336/+/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": "1604451872114599",
"url": "https://ci.chromium.org/b/8864603990100918336",
"utcnow_ts": "1604451872262552"
}
}
[01:04:32.270] Task URL: https://ci.chromium.org/b/8864603990100918336
[01:04:32.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:2:0) after 1m0s
[01:04:37.107] Received PubSub notification, asking Buildbucket for the build status
[01:04:37.616] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:32.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:2:0)
[01:05:32.322] Timer tick, asking Buildbucket for the build status
[01:05:32.409] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:05:32.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:4:0) after 1m0s
[01:06:32.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:4:0)
[01:06:32.487] Timer tick, asking Buildbucket for the build status
[01:06:32.642] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:06:32.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:5:0) after 1m0s
[01:07:32.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:5:0)
[01:07:32.653] Timer tick, asking Buildbucket for the build status
[01:07:32.921] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:07:32.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:6:0) after 1m0s
[01:08:32.963] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:6:0)
[01:08:32.963] Timer tick, asking Buildbucket for the build status
[01:08:33.076] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:08:33.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:7:0) after 1m0s
[01:09:33.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:7:0)
[01:09:33.156] Timer tick, asking Buildbucket for the build status
[01:09:33.314] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:09:33.314] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:8:0) after 1m0s
[01:10:33.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:8:0)
[01:10:33.336] Timer tick, asking Buildbucket for the build status
[01:10:33.489] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:10:33.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:9:0) after 1m0s
[01:11:33.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:9:0)
[01:11:33.530] Timer tick, asking Buildbucket for the build status
[01:11:33.603] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:11:33.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:10:0) after 1m0s
[01:12:33.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:10:0)
[01:12:33.633] Timer tick, asking Buildbucket for the build status
[01:12:33.933] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:12:33.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:11:0) after 1m0s
[01:13:33.960] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:11:0)
[01:13:33.960] Timer tick, asking Buildbucket for the build status
[01:13:34.038] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:13:34.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:12:0) after 1m0s
[01:14:34.091] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:12:0)
[01:14:34.091] Timer tick, asking Buildbucket for the build status
[01:14:34.243] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:14:34.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:13:0) after 1m0s
[01:15:34.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:13:0)
[01:15:34.362] Timer tick, asking Buildbucket for the build status
[01:15:34.436] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:15:34.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:14:0) after 1m0s
[01:16:34.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:14:0)
[01:16:34.533] Timer tick, asking Buildbucket for the build status
[01:16:34.635] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:16:34.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:15:0) after 1m0s
[01:17:34.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:15:0)
[01:17:34.706] Timer tick, asking Buildbucket for the build status
[01:17:34.773] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:17:34.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:16:0) after 1m0s
[01:18:34.868] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:16:0)
[01:18:34.868] Timer tick, asking Buildbucket for the build status
[01:18:34.951] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:18:34.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:17:0) after 1m0s
[01:19:35.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:17:0)
[01:19:35.097] Timer tick, asking Buildbucket for the build status
[01:19:35.249] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:19:35.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:18:0) after 1m0s
[01:20:35.342] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:18:0)
[01:20:35.342] Timer tick, asking Buildbucket for the build status
[01:20:35.539] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:20:35.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:19:0) after 1m0s
[01:21:35.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:19:0)
[01:21:35.590] Timer tick, asking Buildbucket for the build status
[01:21:35.661] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:21:35.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:20:0) after 1m0s
[01:22:35.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:20:0)
[01:22:35.660] Timer tick, asking Buildbucket for the build status
[01:22:35.895] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:22:35.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:21:0) after 1m0s
[01:23:35.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:21:0)
[01:23:35.969] Timer tick, asking Buildbucket for the build status
[01:23:36.126] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:23:36.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:22:0) after 1m0s
[01:24:36.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:22:0)
[01:24:36.195] Timer tick, asking Buildbucket for the build status
[01:24:36.646] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:24:36.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:23:0) after 1m0s
[01:25:36.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:23:0)
[01:25:36.682] Timer tick, asking Buildbucket for the build status
[01:25:36.869] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:25:36.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:24:0) after 1m0s
[01:26:36.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:24:0)
[01:26:36.925] Timer tick, asking Buildbucket for the build status
[01:26:37.052] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:26:37.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:25:0) after 1m0s
[01:27:37.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:25:0)
[01:27:37.119] Timer tick, asking Buildbucket for the build status
[01:27:37.267] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:27:37.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:26:0) after 1m0s
[01:28:37.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:26:0)
[01:28:37.406] Timer tick, asking Buildbucket for the build status
[01:28:37.520] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:28:37.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:27:0) after 1m0s
[01:29:37.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:27:0)
[01:29:37.540] Timer tick, asking Buildbucket for the build status
[01:29:37.756] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:29:37.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:28:0) after 1m0s
[01:30:37.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:28:0)
[01:30:37.869] Timer tick, asking Buildbucket for the build status
[01:30:38.284] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:30:38.284] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:29:0) after 1m0s
[01:31:38.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:29:0)
[01:31:38.412] Timer tick, asking Buildbucket for the build status
[01:31:38.530] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:31:38.530] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:30:0) after 1m0s
[01:32:38.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:30:0)
[01:32:38.867] Timer tick, asking Buildbucket for the build status
[01:32:38.979] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:32:38.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:31:0) after 1m0s
[01:33:39.052] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:31:0)
[01:33:39.052] Timer tick, asking Buildbucket for the build status
[01:33:39.222] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:33:39.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:32:0) after 1m0s
[01:34:39.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:32:0)
[01:34:39.252] Timer tick, asking Buildbucket for the build status
[01:34:39.434] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:34:39.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:33:0) after 1m0s
[01:35:39.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:33:0)
[01:35:39.491] Timer tick, asking Buildbucket for the build status
[01:35:39.619] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:35:39.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:34:0) after 1m0s
[01:36:39.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:34:0)
[01:36:39.669] Timer tick, asking Buildbucket for the build status
[01:36:39.865] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:36:39.865] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:35:0) after 1m0s
[01:37:39.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:35:0)
[01:37:39.900] Timer tick, asking Buildbucket for the build status
[01:37:40.026] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:37:40.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:36:0) after 1m0s
[01:38:40.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:36:0)
[01:38:40.120] Timer tick, asking Buildbucket for the build status
[01:38:40.240] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:38:40.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:37:0) after 1m0s
[01:39:40.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:37:0)
[01:39:40.294] Timer tick, asking Buildbucket for the build status
[01:39:40.897] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:39:40.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:38:0) after 1m0s
[01:40:41.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:38:0)
[01:40:41.077] Timer tick, asking Buildbucket for the build status
[01:40:41.238] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:40:41.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:39:0) after 1m0s
[01:41:41.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:39:0)
[01:41:41.281] Timer tick, asking Buildbucket for the build status
[01:41:41.421] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:41:41.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:40:0) after 1m0s
[01:42:41.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:40:0)
[01:42:41.559] Timer tick, asking Buildbucket for the build status
[01:42:41.728] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:42:41.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:41:0) after 1m0s
[01:43:41.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:41:0)
[01:43:41.804] Timer tick, asking Buildbucket for the build status
[01:43:41.923] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:43:41.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:42:0) after 1m0s
[01:44:42.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:42:0)
[01:44:42.025] Timer tick, asking Buildbucket for the build status
[01:44:42.338] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:44:42.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:43:0) after 1m0s
[01:45:42.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:43:0)
[01:45:42.375] Timer tick, asking Buildbucket for the build status
[01:45:42.461] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:45:42.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:44:0) after 1m0s
[01:46:42.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:44:0)
[01:46:42.507] Timer tick, asking Buildbucket for the build status
[01:46:42.748] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:46:42.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:45:0) after 1m0s
[01:47:42.823] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:45:0)
[01:47:42.823] Timer tick, asking Buildbucket for the build status
[01:47:43.002] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:47:43.002] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:46:0) after 1m0s
[01:48:43.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:46:0)
[01:48:43.094] Timer tick, asking Buildbucket for the build status
[01:48:43.339] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:48:43.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:47:0) after 1m0s
[01:49:43.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:47:0)
[01:49:43.357] Timer tick, asking Buildbucket for the build status
[01:49:43.487] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:49:43.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:48:0) after 1m0s
[01:50:43.530] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:48:0)
[01:50:43.530] Timer tick, asking Buildbucket for the build status
[01:50:43.647] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:50:43.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:49:0) after 1m0s
[01:51:43.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:49:0)
[01:51:43.736] Timer tick, asking Buildbucket for the build status
[01:51:43.851] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:51:43.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:50:0) after 1m0s
[01:52:43.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:50:0)
[01:52:43.844] Timer tick, asking Buildbucket for the build status
[01:52:44.166] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:52:44.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:51:0) after 1m0s
[01:53:44.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:51:0)
[01:53:44.223] Timer tick, asking Buildbucket for the build status
[01:53:44.354] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:53:44.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:52:0) after 1m0s
[01:54:44.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:52:0)
[01:54:44.446] Timer tick, asking Buildbucket for the build status
[01:54:44.726] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:54:44.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:53:0) after 1m0s
[01:55:44.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:53:0)
[01:55:44.796] Timer tick, asking Buildbucket for the build status
[01:55:44.938] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:55:44.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:54:0) after 1m0s
[01:56:45.002] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:54:0)
[01:56:45.002] Timer tick, asking Buildbucket for the build status
[01:56:45.083] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:56:45.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:55:0) after 1m0s
[01:57:45.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:55:0)
[01:57:45.152] Timer tick, asking Buildbucket for the build status
[01:57:45.348] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:57:45.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:56:0) after 1m0s
[01:58:45.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:56:0)
[01:58:45.450] Timer tick, asking Buildbucket for the build status
[01:58:45.538] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:58:45.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:57:0) after 1m0s
[01:59:45.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:57:0)
[01:59:45.544] Timer tick, asking Buildbucket for the build status
[01:59:45.669] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[01:59:45.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:58:0) after 1m0s
[02:00:45.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:58:0)
[02:00:45.820] Timer tick, asking Buildbucket for the build status
[02:00:46.195] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:00:46.195] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:59:0) after 1m0s
[02:01:46.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:59:0)
[02:01:46.271] Timer tick, asking Buildbucket for the build status
[02:01:46.406] Build 8864603990100918336: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:01:46.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030034053205999024:60:0) after 1m0s
[02:02:11.598] Received PubSub notification, asking Buildbucket for the build status
[02:02:11.771] Build 8864603990100918336: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[02:02:11.771] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604455330165933",
"created_by": "project:chromium",
"created_ts": "1604451871736640",
"id": "8864603990100918336",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"60c3fa895f4b80ec0c07f165cd488159c2ff10b9\"}}",
"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\": \"60c3fa895f4b80ec0c07f165cd488159c2ff10b9\", \"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-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15444, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"39adaeb09df024b0baf831314329ba92619f88e1\", \"got_dawn_revision\": \"43ef0a365b8443226916c090b88b3a0b09d6df96\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"60c3fa895f4b80ec0c07f165cd488159c2ff10b9\", \"got_revision_cp\": \"refs/heads/master@{#823821}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b5bf82693f85b53ce31846705d323764a582489b\", \"got_v8_revision\": \"7af8df824417ec1cab5390683d1528bed79b91a1\", \"got_v8_revision_cp\": \"refs/heads/8.8.225@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"60c3fa895f4b80ec0c07f165cd488159c2ff10b9\", \"swarm_hashes_refs/heads/master(at){#823821}_without_patch\": {\"absl_hardening_tests\": \"55b9bbd05aa50000dba90326bb6585936a623ca1\", \"accessibility_unittests\": \"6a55bb333cda6413335e88774477dbe5d5dc0e98\", \"angle_unittests\": \"2c687049387da95ffb8a95be9a765e076af077f1\", \"app_shell_unittests\": \"68e05c9a534d1064a00eaaddbcb81b215e3bc0ad\", \"aura_unittests\": \"122890e129a6a0ee14d0ae83fd38178aa116cca5\", \"base_unittests\": \"83c46e26788396d86976cfa6ef19933a67100956\", \"base_util_unittests\": \"c5c62c91d27e2ad6fdee6c6f01908bcb6a3b693e\", \"blink_common_unittests\": \"da753f4f07774ac2df4deb65f26fe353a8df46d3\", \"blink_fuzzer_unittests\": \"3ea7000d313ee7ac33c9b796fcab2b2c0f98c0c5\", \"blink_heap_unittests\": \"fd18f0c6a76363ad906e298f5f18efe180b634ff\", \"blink_platform_unittests\": \"2ba0200552d7bbe5bb4f2a8e1ebcfd8bee81cde8\", \"blink_unittests\": \"0e4bf7968c451663f941c4c7ac9cfbabfd88a2ed\", \"boringssl_crypto_tests\": \"a700184545bb5cfe70722493295cf42afad8bcc1\", \"boringssl_ssl_tests\": \"772acdbdcb2d6db7781f4cc4daf821cf33992324\", \"browser_switcher_bho_unittests\": \"c46abc46bcbbcb2a676a05d9ece055f5d9617211\", \"browser_tests\": \"958628d2f4cdea205efb4e0bfd28fa845c994519\", \"capture_unittests\": \"28a8f68303a18d81cf884936e2a452ac7ac5a1fc\", \"cast_unittests\": \"39e0dd736a973e44acb78779dcc1c387f96bff06\", \"cc_unittests\": \"39ac7d1b0f552d0a395953825dc7398feb4d7ca2\", \"chrome_app_unittests\": \"d3fbe2c24628342aeb147ee15d11ce3ffadcc48e\", \"chrome_cleaner_unittests\": \"1b0c6ad75041e638a3d255aa4d02d7829bcc0f7f\", \"chrome_elf_unittests\": \"d604553649abc1ab056452a4187e2c211a67e4b7\", \"chromedriver_unittests\": \"2a58fe5880a913f4bbc536c90f0db12f93a72dbb\", \"components_browsertests\": \"86cd4d0a38f7f4da4d4e5bc9423cda573c8ba851\", \"components_unittests\": \"7c4e6ba63b960b37dc967f4dad864d7d05381a99\", \"compositor_unittests\": \"dfc11464e1eaaf1345d328dfdb239463b1c9f9bc\", \"content_browsertests\": \"a8da64020977e3571a896ab856e4fca7285628a4\", \"content_unittests\": \"347479325f5ab29359a6f9cc229f383cd1d6d588\", \"courgette_unittests\": \"0e2771e4bfef4b4387f2dcb688cf152d29de49b2\", \"crashpad_tests\": \"12e34989cff7488a3c24cc22029cd234ed6c3dbb\", \"cronet_tests\": \"af87e2ae2b60acc46f1b30468048307f330f4363\", \"cronet_unittests\": \"e71fb60816c927a5972ede13c9ed11f261743d11\", \"crypto_unittests\": \"50c6e98d2a8d5470f3dec7999959177bee175085\", \"delayloads_unittests\": \"1bed753fc50a164c1913e1059dd2481bf8f561f5\", \"device_unittests\": \"cabcf138634750f957f616206bcab46a1ca5e90b\", \"display_unittests\": \"b2e7b2997b4f536d5bdc8a926363b7026854b308\", \"elevation_service_unittests\": \"e93830d7127b71b419a6e5648d7521ed3be89dfb\", \"events_unittests\": \"e2b50a4416b74003d444cc72cb6d62add357bd4e\", \"extensions_browsertests\": \"08ed111307ddd7937c948c57c1ded7bf54538d99\", \"extensions_unittests\": \"a14636e8bd13099f9842abb5d14abf7c74da2b08\", \"filesystem_service_unittests\": \"76514aeac02cd1852a8de7c1723b73b3cdc1a40f\", \"gcm_unit_tests\": \"70e532bd9b8dbf82b7b78c1efad0f97c752c6b60\", \"gcp_unittests\": \"0d4c5ec36bc2a8201568facbb10711e757464350\", \"gfx_unittests\": \"55181b2d6735fa95f9f5a39b2f81e64e037841e3\", \"gin_unittests\": \"faf4fbe6882a246b40f3eaf88917f88e691bac09\", \"google_apis_unittests\": \"af0403e70737ab7fbc03914834c6b6afe4df1678\", \"gpu_unittests\": \"4725acb1c3a8b04cefe595c37eaf98157935f5b5\", \"gwp_asan_unittests\": \"2789e177de5924230e7778be21bc58c6be0f5d9b\", \"headless_browsertests\": \"faf00ea30c50afe22a812b14f236af631f402f73\", \"headless_unittests\": \"4b74c2d7bd8ba12f015b8e51cae03682114dd0de\", \"install_static_unittests\": \"96cbd637c6fae7568f66953a3657bd2343553638\", \"installer_util_unittests\": \"a4e1fe8e010c6c1535edb83cf2f1882ca3856a69\", \"interactive_ui_tests\": \"47066c7b7b81c1662ef8656465a63f407731c1e6\", \"ipc_tests\": \"e15854113d991aa20752fec30fc0e164290cbe5f\", \"jingle_unittests\": \"195cbc2229305f1ec1b753447fd7e47d6826166c\", \"latency_unittests\": \"4c83fa77f0e887ac9e6956c1a735bf06000c0814\", \"libjingle_xmpp_unittests\": \"328dc75733a85af1c2bdb871c064da9d0635ce85\", \"liburlpattern_unittests\": \"d272cf9d11d09e1da4e5d149fba8d630ef4c4096\", \"media_blink_unittests\": \"de0f501c5f96edec8067cf968d57cc368ebc5b1b\", \"media_unittests\": \"8e3753b8c6f5ffce66ff2e19e7d42732cdb08a36\", \"message_center_unittests\": \"87a61a6d8f614b4cc3042a06fcaac5c03f8a9217\", \"midi_unittests\": \"9b51e9ea627abd835ba54689eabbcd5b9236b5aa\", \"mojo_core_unittests\": \"483a451d942ab6a6e5af3f90ec6974ff5f431d5a\", \"mojo_unittests\": \"97de0716de8a2e592b8d97b46db63ae99816fd27\", \"nacl_loader_unittests\": \"1cee0c76aa352d33ebdf56e476ec44b8257c5439\", \"native_theme_unittests\": \"7ae27522027d8915fd7876a9cb7d189fb02bda19\", \"net_unittests\": \"5caa75a2883f4b1e271a3891ba510bef77958f56\", \"notification_helper_unittests\": \"dbf38ec0a4d62ad3f15253de969c1896394c421a\", \"pdf_unittests\": \"4cdb3dbc205b0aff076b285854fd032bb9f8f4b3\", \"perfetto_unittests\": \"ce1d9554e4ca80d2d1f5eea3807180ab02e469ba\", \"ppapi_unittests\": \"c6b31f4c25ade7cc1e01a72ea2924a748405abc0\", \"printing_unittests\": \"38fd115d2afd9d00826a5c33c58bd6b2c21df696\", \"remoting_unittests\": \"9e7ab528e42455474b19fc1b8f160eaffde2476f\", \"sbox_integration_tests\": \"5d74e38e9403007ac7178e4f49f919847ab739cf\", \"sbox_unittests\": \"2f491b9b5695a1f5de814ba62564da0a650def9a\", \"sbox_validation_tests\": \"bd5c4cea370c5ad63158e4487b316804728f14ea\", \"service_manager_unittests\": \"65a72cdcef2ad600e97a7f0b0a0b63225ffe7212\", \"services_unittests\": \"9107df76029182c01cf0ec3922efc9deaf25fd18\", \"setup_unittests\": \"efbdbfb55d4c347c5074f1b4ea65d805e915f7e5\", \"shell_dialogs_unittests\": \"26fae95b450da560caf4baaf83ceea849f8e5a28\", \"skia_unittests\": \"a49568a6f11afebb5482d3934112d7132278fab1\", \"snapshot_unittests\": \"9bbf21192c75c4d37dbe70aef08271710c9928ae\", \"sql_unittests\": \"5ddb6500e5335a5b4e78e7db17483c78eee8fee5\", \"storage_unittests\": \"f18f109ba0a3a4df5561b717d189c30eede36b66\", \"sync_integration_tests\": \"05d215fe54c224fd56dac5807f56d1fb610ba24e\", \"traffic_annotation_auditor_unittests\": \"3ee4add5cea25cf7783e383f52d88ef9aeda125c\", \"ui_base_unittests\": \"9566d5be737ce0c025cd84601dcd138002089d7f\", \"ui_touch_selection_unittests\": \"c8cfecaa4f0b8c0010caf5ebda576de5ee771d23\", \"unit_tests\": \"a5685bc68acde055252a666ee3a1c8d8c7f95d66\", \"updater_tests\": \"d77cd66fb80c3e7066f95db92d039f5166d7d9e3\", \"url_unittests\": \"d389f98b15684e1fd7a484c2405de1640e84b25f\", \"views_unittests\": \"40bb3173b9abf771af911f73b7b65aeda5ed5c32\", \"viz_unittests\": \"9665795a5928f3f85c07530ca23caa9cfc6e469e\", \"vr_common_unittests\": \"cb94e40da1b5efed7767cb8f236a7eaff9f083a7\", \"vr_pixeltests\": \"a816ee1a0f5cb7d5c0a82c90de80a7fb52245d0f\", \"weblayer_browsertests\": \"1ab56c3a74a1d5310a1efe00ceb45a3c424af452\", \"weblayer_unittests\": \"1b51c091b2801cbc2e3b19c3e8769565a6b4ba4f\", \"wm_unittests\": \"46e709b3bc2663c5ada59d5e427fbecb1e0f4f6b\", \"wtf_unittests\": \"1eed738f4a3ae46c895657dae9f5b5717ea84c33\", \"zlib_unittests\": \"d4bebfa0bcc4fa25f5428731d52bd43c512326dd\", \"zucchini_unittests\": \"c5f0c3b8c4f9c7cc49e242e86e0445e7923cfb08\"}}, \"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-5-vrjs\"], \"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\": [\"5614-d862eaa\"], \"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": "1604451876422745",
"status": "COMPLETED",
"status_changed_ts": "1604455330711714",
"tags": [
"build_address:luci.chromium.ci/win-asan/15444",
"builder:win-asan",
"buildset:commit/git/60c3fa895f4b80ec0c07f165cd488159c2ff10b9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/60c3fa895f4b80ec0c07f165cd488159c2ff10b9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030034053205999024",
"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/8864603990100918336/+/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:4fa99a36e6248810",
"user_agent:luci-scheduler"
],
"updated_ts": "1604455330734843",
"url": "https://ci.chromium.org/b/8864603990100918336",
"utcnow_ts": "1604455331743976"
}
}
[02:02:11.771] Invocation finished in 57m42.379945899s with status SUCCEEDED