[18:42:06.467] New invocation is queued and will start shortly
[18:42:07.569] Starting the invocation (attempt 1)
[18:42:07.604] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:42:07.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:42:07.604] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024169307806438880",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e2cf5fa1f67d34996c217249a3664e943de7f5d8\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024169307806438880",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e2cf5fa1f67d34996c217249a3664e943de7f5d8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2cf5fa1f67d34996c217249a3664e943de7f5d8",
"gitiles_ref:refs/heads/master"
]
}
[18:42:09.225] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610044927735642",
"id": "8858739245813960448",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e2cf5fa1f67d34996c217249a3664e943de7f5d8\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610044928850136",
"tags": [
"build_address:luci.chromium.ci/win-asan/16678",
"builder:win-asan",
"buildset:commit/git/e2cf5fa1f67d34996c217249a3664e943de7f5d8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2cf5fa1f67d34996c217249a3664e943de7f5d8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024169307806438880",
"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/8858739245813960448/+/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": "1610044928850273",
"url": "https://ci.chromium.org/b/8858739245813960448",
"utcnow_ts": "1610044929217860"
}
}
[18:42:09.225] Task URL: https://ci.chromium.org/b/8858739245813960448
[18:42:09.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:2:0) after 1m0s
[18:42:12.568] Received PubSub notification, asking Buildbucket for the build status
[18:42:12.674] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:09.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:2:0)
[18:43:09.257] Timer tick, asking Buildbucket for the build status
[18:43:09.580] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:43:09.580] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:4:0) after 1m0s
[18:44:09.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:4:0)
[18:44:09.692] Timer tick, asking Buildbucket for the build status
[18:44:09.823] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:44:09.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:5:0) after 1m0s
[18:45:09.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:5:0)
[18:45:09.931] Timer tick, asking Buildbucket for the build status
[18:45:10.272] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:45:10.273] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:6:0) after 1m0s
[18:46:10.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:6:0)
[18:46:10.362] Timer tick, asking Buildbucket for the build status
[18:46:10.487] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:46:10.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:7:0) after 1m0s
[18:47:10.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:7:0)
[18:47:10.506] Timer tick, asking Buildbucket for the build status
[18:47:10.754] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:47:10.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:8:0) after 1m0s
[18:48:10.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:8:0)
[18:48:10.775] Timer tick, asking Buildbucket for the build status
[18:48:10.879] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:48:10.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:9:0) after 1m0s
[18:49:11.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:9:0)
[18:49:11.003] Timer tick, asking Buildbucket for the build status
[18:49:11.282] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:49:11.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:10:0) after 1m0s
[18:50:11.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:10:0)
[18:50:11.312] Timer tick, asking Buildbucket for the build status
[18:50:11.425] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:50:11.425] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:11:0) after 1m0s
[18:51:11.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:11:0)
[18:51:11.462] Timer tick, asking Buildbucket for the build status
[18:51:11.527] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:51:11.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:12:0) after 1m0s
[18:52:11.570] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:12:0)
[18:52:11.570] Timer tick, asking Buildbucket for the build status
[18:52:11.693] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:52:11.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:13:0) after 1m0s
[18:53:11.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:13:0)
[18:53:11.749] Timer tick, asking Buildbucket for the build status
[18:53:11.820] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:53:11.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:14:0) after 1m0s
[18:54:11.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:14:0)
[18:54:11.825] Timer tick, asking Buildbucket for the build status
[18:54:11.927] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:54:11.927] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:15:0) after 1m0s
[18:55:11.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:15:0)
[18:55:11.959] Timer tick, asking Buildbucket for the build status
[18:55:12.120] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:55:12.120] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:16:0) after 1m0s
[18:56:12.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:16:0)
[18:56:12.148] Timer tick, asking Buildbucket for the build status
[18:56:12.224] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:56:12.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:17:0) after 1m0s
[18:57:12.252] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:17:0)
[18:57:12.252] Timer tick, asking Buildbucket for the build status
[18:57:12.428] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:57:12.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:18:0) after 1m0s
[18:58:12.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:18:0)
[18:58:12.489] Timer tick, asking Buildbucket for the build status
[18:58:12.602] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:58:12.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:19:0) after 1m0s
[18:59:12.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:19:0)
[18:59:12.662] Timer tick, asking Buildbucket for the build status
[18:59:13.076] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:59:13.076] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:20:0) after 1m0s
[19:00:13.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:20:0)
[19:00:13.134] Timer tick, asking Buildbucket for the build status
[19:00:13.325] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:00:13.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:21:0) after 1m0s
[19:01:13.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:21:0)
[19:01:13.686] Timer tick, asking Buildbucket for the build status
[19:01:13.893] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:01:13.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:22:0) after 1m0s
[19:02:13.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:22:0)
[19:02:13.948] Timer tick, asking Buildbucket for the build status
[19:02:14.375] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:02:14.375] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:23:0) after 1m0s
[19:03:14.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:23:0)
[19:03:14.524] Timer tick, asking Buildbucket for the build status
[19:03:14.792] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:03:14.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:24:0) after 1m0s
[19:04:14.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:24:0)
[19:04:14.848] Timer tick, asking Buildbucket for the build status
[19:04:15.547] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:04:15.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:25:0) after 1m0s
[19:05:15.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:25:0)
[19:05:15.760] Timer tick, asking Buildbucket for the build status
[19:05:15.971] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:15.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:26:0) after 1m0s
[19:06:16.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:26:0)
[19:06:16.042] Timer tick, asking Buildbucket for the build status
[19:06:16.187] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:16.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:27:0) after 1m0s
[19:07:16.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:27:0)
[19:07:16.355] Timer tick, asking Buildbucket for the build status
[19:07:16.778] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:16.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:28:0) after 1m0s
[19:08:16.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:28:0)
[19:08:16.825] Timer tick, asking Buildbucket for the build status
[19:08:17.053] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:17.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:29:0) after 1m0s
[19:09:17.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:29:0)
[19:09:17.038] Timer tick, asking Buildbucket for the build status
[19:09:17.332] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:17.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:30:0) after 1m0s
[19:10:17.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:30:0)
[19:10:17.693] Timer tick, asking Buildbucket for the build status
[19:10:17.855] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:17.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:31:0) after 1m0s
[19:11:17.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:31:0)
[19:11:17.918] Timer tick, asking Buildbucket for the build status
[19:11:18.066] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:18.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:32:0) after 1m0s
[19:12:18.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:32:0)
[19:12:18.243] Timer tick, asking Buildbucket for the build status
[19:12:18.710] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:18.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:33:0) after 1m0s
[19:13:18.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:33:0)
[19:13:18.882] Timer tick, asking Buildbucket for the build status
[19:13:18.970] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:18.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:34:0) after 1m0s
[19:14:19.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:34:0)
[19:14:19.049] Timer tick, asking Buildbucket for the build status
[19:14:19.186] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:19.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:35:0) after 1m0s
[19:15:19.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:35:0)
[19:15:19.299] Timer tick, asking Buildbucket for the build status
[19:15:19.617] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:19.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:36:0) after 1m0s
[19:16:19.732] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:36:0)
[19:16:19.732] Timer tick, asking Buildbucket for the build status
[19:16:19.880] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:19.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:37:0) after 1m0s
[19:17:19.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:37:0)
[19:17:19.926] Timer tick, asking Buildbucket for the build status
[19:17:20.042] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:20.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:38:0) after 1m0s
[19:18:20.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:38:0)
[19:18:20.044] Timer tick, asking Buildbucket for the build status
[19:18:20.142] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:20.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:39:0) after 1m0s
[19:19:20.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:39:0)
[19:19:20.234] Timer tick, asking Buildbucket for the build status
[19:19:20.303] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:20.304] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:40:0) after 1m0s
[19:20:20.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:40:0)
[19:20:20.488] Timer tick, asking Buildbucket for the build status
[19:20:20.582] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:20.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:41:0) after 1m0s
[19:21:20.709] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:41:0)
[19:21:20.709] Timer tick, asking Buildbucket for the build status
[19:21:20.779] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:20.779] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:42:0) after 1m0s
[19:22:20.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:42:0)
[19:22:20.831] Timer tick, asking Buildbucket for the build status
[19:22:21.165] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:21.165] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:43:0) after 1m0s
[19:23:21.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:43:0)
[19:23:21.219] Timer tick, asking Buildbucket for the build status
[19:23:21.499] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:21.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:44:0) after 1m0s
[19:24:21.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:44:0)
[19:24:21.526] Timer tick, asking Buildbucket for the build status
[19:24:23.112] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:23.112] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:45:0) after 1m0s
[19:25:23.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:45:0)
[19:25:23.102] Timer tick, asking Buildbucket for the build status
[19:25:23.519] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:23.519] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:46:0) after 1m0s
[19:26:23.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:46:0)
[19:26:23.545] Timer tick, asking Buildbucket for the build status
[19:26:23.788] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:23.788] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:47:0) after 1m0s
[19:27:23.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:47:0)
[19:27:23.805] Timer tick, asking Buildbucket for the build status
[19:27:23.935] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:23.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:48:0) after 1m0s
[19:28:23.982] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:48:0)
[19:28:23.982] Timer tick, asking Buildbucket for the build status
[19:28:24.064] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:24.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:49:0) after 1m0s
[19:29:24.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:49:0)
[19:29:24.085] Timer tick, asking Buildbucket for the build status
[19:29:24.224] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:24.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:50:0) after 1m0s
[19:30:24.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:50:0)
[19:30:24.284] Timer tick, asking Buildbucket for the build status
[19:30:24.472] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:51:0) after 1m0s
[19:31:24.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:51:0)
[19:31:24.477] Timer tick, asking Buildbucket for the build status
[19:31:24.738] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:24.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:52:0) after 1m0s
[19:32:24.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:52:0)
[19:32:24.765] Timer tick, asking Buildbucket for the build status
[19:32:24.929] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:24.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:53:0) after 1m0s
[19:33:24.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:53:0)
[19:33:24.936] Timer tick, asking Buildbucket for the build status
[19:33:25.125] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:25.125] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:54:0) after 1m0s
[19:34:25.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:54:0)
[19:34:25.127] Timer tick, asking Buildbucket for the build status
[19:34:25.313] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:25.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:55:0) after 1m0s
[19:35:25.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:55:0)
[19:35:25.332] Timer tick, asking Buildbucket for the build status
[19:35:25.534] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:25.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:56:0) after 1m0s
[19:36:25.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:56:0)
[19:36:25.546] Timer tick, asking Buildbucket for the build status
[19:36:25.718] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:25.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:57:0) after 1m0s
[19:37:25.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:57:0)
[19:37:25.815] Timer tick, asking Buildbucket for the build status
[19:37:26.094] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:26.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:58:0) after 1m0s
[19:38:26.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:58:0)
[19:38:26.117] Timer tick, asking Buildbucket for the build status
[19:38:26.191] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:26.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:59:0) after 1m0s
[19:39:26.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:59:0)
[19:39:26.222] Timer tick, asking Buildbucket for the build status
[19:39:26.291] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:26.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:60:0) after 1m0s
[19:40:26.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:60:0)
[19:40:26.307] Timer tick, asking Buildbucket for the build status
[19:40:26.400] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:26.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:61:0) after 1m0s
[19:41:26.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:61:0)
[19:41:26.464] Timer tick, asking Buildbucket for the build status
[19:41:26.617] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:26.617] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:62:0) after 1m0s
[19:42:26.702] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:62:0)
[19:42:26.702] Timer tick, asking Buildbucket for the build status
[19:42:27.559] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:27.559] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:63:0) after 1m0s
[19:43:27.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:63:0)
[19:43:27.658] Timer tick, asking Buildbucket for the build status
[19:43:27.762] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:27.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:64:0) after 1m0s
[19:44:27.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:64:0)
[19:44:27.835] Timer tick, asking Buildbucket for the build status
[19:44:28.227] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:28.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:65:0) after 1m0s
[19:45:28.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:65:0)
[19:45:28.410] Timer tick, asking Buildbucket for the build status
[19:45:28.484] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:28.484] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:66:0) after 1m0s
[19:46:28.544] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:66:0)
[19:46:28.544] Timer tick, asking Buildbucket for the build status
[19:46:28.699] Build 8858739245813960448: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:28.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024169307806438880:67:0) after 1m0s
[19:47:27.186] Received PubSub notification, asking Buildbucket for the build status
[19:47:27.324] Build 8858739245813960448: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[19:47:27.324] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610048846223601",
"created_by": "project:chromium",
"created_ts": "1610044927735642",
"id": "8858739245813960448",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e2cf5fa1f67d34996c217249a3664e943de7f5d8\"}}",
"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\": \"e2cf5fa1f67d34996c217249a3664e943de7f5d8\", \"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-7-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16678, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"d0a38010a14cf01b1a7b32143315108bd3334f46\", \"got_dawn_revision\": \"06aa1af28a72833fd59531763a0bee0338811f5b\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"e2cf5fa1f67d34996c217249a3664e943de7f5d8\", \"got_revision_cp\": \"refs/heads/master@{#841118}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"7b2f938954180e8945f776d3e202f6a2bda1aaf9\", \"got_v8_revision\": \"d1da011fb05beda92d918cf7b378ddf7b7386c9b\", \"got_v8_revision_cp\": \"refs/heads/8.9.214@{#1}\", \"got_webrtc_revision\": \"fc6b87192db6d5e4fd6997814f00b6792cf16180\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32904}\", \"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\": \"e2cf5fa1f67d34996c217249a3664e943de7f5d8\", \"swarm_hashes_refs/heads/master(at){#841118}_without_patch\": {\"absl_hardening_tests\": \"e4209e29fc535a6f0701c3c8b9ed1f27d360c80a\", \"accessibility_unittests\": \"c6e9fee3503fc2c484cc4577411b4c0c798df7db\", \"angle_unittests\": \"30af18f3f72b2ff587ea79fa05b59c3487989666\", \"app_shell_unittests\": \"120ea77c9e02c3980bdb47e9e7403cf5a46ba7ac\", \"aura_unittests\": \"2160257fb61842510cb8278b891ea9528211ee74\", \"base_unittests\": \"121076c226a925e4194c106c6134601f7d7fa359\", \"base_util_unittests\": \"7679555d2d84ba1f130a6ef1d2662df5426bfd28\", \"blink_common_unittests\": \"9950b977f791334ee84c48969e0dfd7a0c12a2aa\", \"blink_fuzzer_unittests\": \"225733a176fedcb9e34539c80bea883b712b1e1f\", \"blink_heap_unittests\": \"40b9008d73f7aa466a9f2a8c40ab39531dba4cdf\", \"blink_platform_unittests\": \"cdc777eda703201eac079e35b17b74e038769940\", \"blink_unittests\": \"03fbcb152052196f026925f0fa9e4469254b8d95\", \"boringssl_crypto_tests\": \"4e468000f93d804aeb20ac1e2ffc1379afcf5e3e\", \"boringssl_ssl_tests\": \"f7bc466bdb8eacd5835b475e23efb1d6ff5cb7f9\", \"browser_switcher_bho_unittests\": \"0dcd01372a29dc2c9866928dd95f06230b63ad96\", \"browser_tests\": \"86a40995e86960b11f9b7d7d4fb3786b17c8bad0\", \"capture_unittests\": \"72a8657cb54fbd87f10a944f98faa9057411bf06\", \"cast_unittests\": \"6e903c515535f415d24fda9868bac526082121bd\", \"cc_unittests\": \"12a5fa0c3c1fabb98e3346d5441325e5e100294b\", \"chrome_app_unittests\": \"6125ec4d12d4ed6ed16f9cbcd48ae2cadd61cca9\", \"chrome_cleaner_unittests\": \"646ce73b9c2d1eebb4aa1fa9d067170a7029b9ee\", \"chrome_elf_unittests\": \"c863f9dea074f01ac4d00eeecee7dbd61891d614\", \"chromedriver_unittests\": \"a37a4d245ae77c98e5f1f48c8e03c5ab9deabddd\", \"components_browsertests\": \"38635b44efb42430ba8c6ae8c0e9fc8653ee2170\", \"components_unittests\": \"1ebfce66e598cf81b90c5a6f6b7d33e78913b366\", \"compositor_unittests\": \"38be8b3e4ce884d19fde3ebc718642bf6a9b033b\", \"content_browsertests\": \"4ac0681e02be7f67d7b0d9cee893a011b2fc1c34\", \"content_unittests\": \"d19959854862ddef2cb1d0e374950bd22c30acd9\", \"courgette_unittests\": \"7c1d7ec2308e31c8d2e94b7a79056cb2400951ce\", \"crashpad_tests\": \"8f44802155bdd57a64e287b922356cedaba52fb6\", \"cronet_tests\": \"ce68bdd936b8cdb79d10f34c49ac51e4e9913049\", \"cronet_unittests\": \"93d5f57ed40c82aff586ff2560d4e79baca7ea7c\", \"crypto_unittests\": \"828e7a94bf19e0f03ee19495b5db1c5ad47c974d\", \"delayloads_unittests\": \"88390aa9ea3f1b92855a1f65f54bffd803e73861\", \"device_unittests\": \"e4916eedbf9adad597a83fd5ccbdef4d8e33d6e0\", \"display_unittests\": \"40d08384b153d4ca3808237a4691384e5a4561bb\", \"elevation_service_unittests\": \"dc3096b9777450e341e2a64de15884a835d2a95f\", \"events_unittests\": \"62a38d63a37b32dc47da1c223cf7e3cc9b8b32b5\", \"extensions_browsertests\": \"93ae88628fa8d4dd0374ddf253786886b8ca7959\", \"extensions_unittests\": \"e82a225701c30683f97ffc976b157964ce0995a8\", \"filesystem_service_unittests\": \"8d54f651002651fcebc06a6b2cbb905fd51be976\", \"gcm_unit_tests\": \"a7bf0ccffff307b337c4b44622936f3150c921a6\", \"gcp_unittests\": \"67d059b185cc68f5eb9eb94e50b2c3c8ba7c8993\", \"gfx_unittests\": \"66b84bc1db631899a994f681362be6727338d89f\", \"gin_unittests\": \"b2da80cafecfb9a916f3fe714d375285d8f98e75\", \"google_apis_unittests\": \"10dc0b9372c3f9890cddd05240d50cde818982a9\", \"gpu_unittests\": \"b1a7b37de91f2056883828d7530005803bc17d0c\", \"gwp_asan_unittests\": \"1858a5dadb07e679878926dbfa87d612ba356100\", \"headless_browsertests\": \"4caa817b8f3598c92e5325d42f283900ea951df8\", \"headless_unittests\": \"cb78affbbbe81849f714031cc9d49bd16ad0ebd9\", \"install_static_unittests\": \"010d4b75b0c43ef6ee5fd7eefa3fff8d925a5178\", \"installer_util_unittests\": \"88672b86b396f0c4f390e1297401c3b749b2c536\", \"interactive_ui_tests\": \"d1358dcb122db847bc8c80a8bc792572f4771a4b\", \"ipc_tests\": \"749f322a4ce0835e5ac86e53d203a7b9b7b72388\", \"jingle_unittests\": \"fcf29e1a44c57725cb8442774b4e46ca7660a86d\", \"latency_unittests\": \"b61eb633e69da74d543fa3a50cc5ed07c6139b19\", \"libjingle_xmpp_unittests\": \"b9944c0f6ec3498019734f4685edf7d9c9e42a9a\", \"liburlpattern_unittests\": \"23e511b5f040bb7d22db30a9782b061454ff155d\", \"media_blink_unittests\": \"a7d3ad8aeced662fbc1eef5161063182e3c5f7dd\", \"media_unittests\": \"56a40120ccf95957ac265ba96b0f48a24303d6d9\", \"message_center_unittests\": \"822c9ed27706b93b376fab31bb01229ab8cfbb6c\", \"midi_unittests\": \"3d46883ab2573acda8317a0953c66c7f9669fcd1\", \"mojo_core_unittests\": \"8b8abefbdf9e62d48472fc465baa3d305d021440\", \"mojo_unittests\": \"fe100ccfa05f5b2b52d293c0899639923beb235e\", \"nacl_loader_unittests\": \"533b3dce9c6a9dafaa7f8b6ef7db7e3623b2b7c0\", \"native_theme_unittests\": \"e0a0868f2d8ccae8465b7d3714eb46ac39b2ad49\", \"net_unittests\": \"e335bdcb36db30c15a6fec5b240d499cf5d79a69\", \"notification_helper_unittests\": \"cd116eeffa4dbc67aa8825ff7b762b616fb4d933\", \"pdf_unittests\": \"e808479a5247e37b3f4c68a31c081119be46d123\", \"perfetto_unittests\": \"c37ae5f483bb51310f1e8bfb3bd66cb32bb6c129\", \"ppapi_unittests\": \"ac68fa73bc85c7bc3163f94f6d6147e768668d9f\", \"printing_unittests\": \"0c54b1d25bf732f9334fa0b5ea88466dd27da535\", \"remoting_unittests\": \"d2d10350fd2103c75597a24a32a15ae37ec21bd4\", \"sbox_integration_tests\": \"e7c5ba3c8efbed35e858080739c94c013326954a\", \"sbox_unittests\": \"cb6ef041ef15bfda4a36d04d03ad11f6c97fe30c\", \"sbox_validation_tests\": \"8de54038b74f6e3c3b1da2aa14960e97d7faa3ea\", \"service_manager_unittests\": \"38903f8745d93ebe9b04d624b1c4d560aeaa54be\", \"services_unittests\": \"93530a8aaf9830252d3e28748e8360ed8dc829a1\", \"setup_unittests\": \"96803bfa93ee2d9e3dea12efb940ac24b6b71173\", \"shell_dialogs_unittests\": \"39e3dd65b2292a6e51a12464956b0a4024263830\", \"skia_unittests\": \"fcb353c1503edcecfd3bb0db30665239e2b3d7e6\", \"snapshot_unittests\": \"b1c69636bdedc2975e35314edcfa9d5a1ca07bf6\", \"sql_unittests\": \"7449d985c37edfe814d83e1f39116f82a8bf8b0f\", \"storage_unittests\": \"f5918bcbd2fe2d7d91529c4e6a9e10663b0f9e77\", \"sync_integration_tests\": \"4c67ba1cbf43a680eab7885ec26092b5a3ab2305\", \"traffic_annotation_auditor_unittests\": \"e192bed3ec97fc7297990fc4d670a631e3b6c009\", \"ui_base_unittests\": \"f71f559a398ce29fc314cd7be1111d6fa04d828b\", \"ui_touch_selection_unittests\": \"51be9b2998d05091f981f778e6882bec53a73ce5\", \"unit_tests\": \"caea7fb44a3d062c8481d1948b7c376c250bfeb7\", \"updater_tests\": \"9dd29e73884a937da6cfe74657b40ae376cbe0d8\", \"url_unittests\": \"f80900d0992b0d759c2ca96ac8be20ba13dbd41d\", \"views_unittests\": \"b5b1c06533b839cdfd973b4a3fa6e6d12aab1c45\", \"viz_unittests\": \"16630572a0cf430c5dd4c9e320dd324abb81b288\", \"vr_common_unittests\": \"855d60a1888a4b6be596c6f3c21543938ea02430\", \"vr_pixeltests\": \"0871e5f12c3a498201a0a671c9997494fd9697ba\", \"weblayer_browsertests\": \"189812294938a912268f1a08cf6cf4b906a9ff64\", \"weblayer_unittests\": \"c31f8b019d776344593594c90e9561844c5de711\", \"wm_unittests\": \"7d72d68dcbc0e98d9d9e8487d0b96f792c551ae5\", \"wtf_unittests\": \"f0c7f403586450169a2b9c23a5f9c1f6a725285f\", \"zlib_unittests\": \"26fc2e864722f1b888e8ee65659028b641ef7b7b\", \"zucchini_unittests\": \"9df673ea487ba85bb790c0119c698c1e1a049c77\"}}, \"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-7-w73f\"], \"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-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1610044931406670",
"status": "COMPLETED",
"status_changed_ts": "1610048846531586",
"tags": [
"build_address:luci.chromium.ci/win-asan/16678",
"builder:win-asan",
"buildset:commit/git/e2cf5fa1f67d34996c217249a3664e943de7f5d8",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e2cf5fa1f67d34996c217249a3664e943de7f5d8",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024169307806438880",
"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/8858739245813960448/+/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:50f6f97e17b25a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610048846551524",
"url": "https://ci.chromium.org/b/8858739245813960448",
"utcnow_ts": "1610048847313969"
}
}
[19:47:27.324] Invocation finished in 1h5m20.873236812s with status SUCCEEDED