[02:30:31.226] New invocation is queued and will start shortly
[02:30:32.361] Starting the invocation (attempt 1)
[02:30:32.414] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:30:32.414] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:30:32.414] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029938043684035520",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"f86023cac54e158577dbe0092a7b8e8c4e1654d9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029938043684035520",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/f86023cac54e158577dbe0092a7b8e8c4e1654d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f86023cac54e158577dbe0092a7b8e8c4e1654d9",
"gitiles_ref:refs/heads/master"
]
}
[02:30:34.053] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604543432544450",
"id": "8864507981635286272",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f86023cac54e158577dbe0092a7b8e8c4e1654d9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604543433688678",
"tags": [
"build_address:luci.chromium.ci/win-asan/15467",
"builder:win-asan",
"buildset:commit/git/f86023cac54e158577dbe0092a7b8e8c4e1654d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f86023cac54e158577dbe0092a7b8e8c4e1654d9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029938043684035520",
"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/8864507981635286272/+/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": "1604543433689540",
"url": "https://ci.chromium.org/b/8864507981635286272",
"utcnow_ts": "1604543433950653"
}
}
[02:30:34.053] Task URL: https://ci.chromium.org/b/8864507981635286272
[02:30:34.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:2:0) after 1m0s
[02:30:38.454] Received PubSub notification, asking Buildbucket for the build status
[02:30:38.579] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:34.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:2:0)
[02:31:34.127] Timer tick, asking Buildbucket for the build status
[02:31:34.260] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:34.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:4:0) after 1m0s
[02:32:34.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:4:0)
[02:32:34.368] Timer tick, asking Buildbucket for the build status
[02:32:34.476] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:34.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:5:0) after 1m0s
[02:33:34.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:5:0)
[02:33:34.564] Timer tick, asking Buildbucket for the build status
[02:33:34.696] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:34.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:6:0) after 1m0s
[02:34:34.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:6:0)
[02:34:34.736] Timer tick, asking Buildbucket for the build status
[02:34:34.854] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:34.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:7:0) after 1m0s
[02:35:34.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:7:0)
[02:35:34.988] Timer tick, asking Buildbucket for the build status
[02:35:35.107] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:35.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:8:0) after 1m0s
[02:36:35.161] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:8:0)
[02:36:35.161] Timer tick, asking Buildbucket for the build status
[02:36:35.300] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:35.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:9:0) after 1m0s
[02:37:35.402] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:9:0)
[02:37:35.402] Timer tick, asking Buildbucket for the build status
[02:37:35.724] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:35.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:10:0) after 1m0s
[02:38:35.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:10:0)
[02:38:35.898] Timer tick, asking Buildbucket for the build status
[02:38:35.997] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:35.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:11:0) after 1m0s
[02:39:35.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:11:0)
[02:39:35.990] Timer tick, asking Buildbucket for the build status
[02:39:36.118] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:36.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:12:0) after 1m0s
[02:40:36.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:12:0)
[02:40:36.155] Timer tick, asking Buildbucket for the build status
[02:40:36.282] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:36.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:13:0) after 1m0s
[02:41:36.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:13:0)
[02:41:36.314] Timer tick, asking Buildbucket for the build status
[02:41:36.458] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:36.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:14:0) after 1m0s
[02:42:36.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:14:0)
[02:42:36.513] Timer tick, asking Buildbucket for the build status
[02:42:36.582] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:36.582] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:15:0) after 1m0s
[02:43:36.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:15:0)
[02:43:36.636] Timer tick, asking Buildbucket for the build status
[02:43:36.767] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:36.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:16:0) after 1m0s
[02:44:36.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:16:0)
[02:44:36.786] Timer tick, asking Buildbucket for the build status
[02:44:37.013] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:37.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:17:0) after 1m0s
[02:45:37.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:17:0)
[02:45:37.037] Timer tick, asking Buildbucket for the build status
[02:45:37.154] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:37.154] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:18:0) after 1m0s
[02:46:37.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:18:0)
[02:46:37.200] Timer tick, asking Buildbucket for the build status
[02:46:37.621] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:37.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:19:0) after 1m0s
[02:47:37.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:19:0)
[02:47:37.672] Timer tick, asking Buildbucket for the build status
[02:47:37.792] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:37.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:20:0) after 1m0s
[02:48:37.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:20:0)
[02:48:37.840] Timer tick, asking Buildbucket for the build status
[02:48:38.553] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:38.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:21:0) after 1m0s
[02:49:38.629] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:21:0)
[02:49:38.629] Timer tick, asking Buildbucket for the build status
[02:49:38.828] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:38.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:22:0) after 1m0s
[02:50:38.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:22:0)
[02:50:38.850] Timer tick, asking Buildbucket for the build status
[02:50:39.006] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:39.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:23:0) after 1m0s
[02:51:39.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:23:0)
[02:51:39.126] Timer tick, asking Buildbucket for the build status
[02:51:39.453] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:39.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:24:0) after 1m0s
[02:52:39.555] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:24:0)
[02:52:39.555] Timer tick, asking Buildbucket for the build status
[02:52:39.706] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:39.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:25:0) after 1m0s
[02:53:39.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:25:0)
[02:53:39.726] Timer tick, asking Buildbucket for the build status
[02:53:39.875] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:39.875] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:26:0) after 1m0s
[02:54:39.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:26:0)
[02:54:39.898] Timer tick, asking Buildbucket for the build status
[02:54:40.042] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:40.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:27:0) after 1m0s
[02:55:40.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:27:0)
[02:55:40.120] Timer tick, asking Buildbucket for the build status
[02:55:40.257] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:40.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:28:0) after 1m0s
[02:56:40.271] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:28:0)
[02:56:40.271] Timer tick, asking Buildbucket for the build status
[02:56:40.348] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:40.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:29:0) after 1m0s
[02:57:40.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:29:0)
[02:57:40.375] Timer tick, asking Buildbucket for the build status
[02:57:40.449] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:40.449] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:30:0) after 1m0s
[02:58:40.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:30:0)
[02:58:40.477] Timer tick, asking Buildbucket for the build status
[02:58:40.641] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:40.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:31:0) after 1m0s
[02:59:40.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:31:0)
[02:59:40.668] Timer tick, asking Buildbucket for the build status
[02:59:40.741] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:40.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:32:0) after 1m0s
[03:00:40.834] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:32:0)
[03:00:40.834] Timer tick, asking Buildbucket for the build status
[03:00:41.144] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:41.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:33:0) after 1m0s
[03:01:41.172] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:33:0)
[03:01:41.173] Timer tick, asking Buildbucket for the build status
[03:01:41.283] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:41.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:34:0) after 1m0s
[03:02:41.327] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:34:0)
[03:02:41.328] Timer tick, asking Buildbucket for the build status
[03:02:41.487] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:41.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:35:0) after 1m0s
[03:03:41.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:35:0)
[03:03:41.532] Timer tick, asking Buildbucket for the build status
[03:03:41.594] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:41.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:36:0) after 1m0s
[03:04:41.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:36:0)
[03:04:41.648] Timer tick, asking Buildbucket for the build status
[03:04:41.782] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:41.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:37:0) after 1m0s
[03:05:41.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:37:0)
[03:05:41.848] Timer tick, asking Buildbucket for the build status
[03:05:42.069] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:42.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:38:0) after 1m0s
[03:06:42.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:38:0)
[03:06:42.157] Timer tick, asking Buildbucket for the build status
[03:06:43.373] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:43.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:39:0) after 1m0s
[03:07:43.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:39:0)
[03:07:43.400] Timer tick, asking Buildbucket for the build status
[03:07:43.506] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:43.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:40:0) after 1m0s
[03:08:43.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:40:0)
[03:08:43.571] Timer tick, asking Buildbucket for the build status
[03:08:43.785] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:43.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:41:0) after 1m0s
[03:09:43.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:41:0)
[03:09:43.848] Timer tick, asking Buildbucket for the build status
[03:09:44.007] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:44.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:42:0) after 1m0s
[03:10:43.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:42:0)
[03:10:43.983] Timer tick, asking Buildbucket for the build status
[03:10:44.263] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:44.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:43:0) after 1m0s
[03:11:44.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:43:0)
[03:11:44.361] Timer tick, asking Buildbucket for the build status
[03:11:45.959] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:45.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:44:0) after 1m0s
[03:12:46.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:44:0)
[03:12:46.041] Timer tick, asking Buildbucket for the build status
[03:12:46.205] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:46.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:45:0) after 1m0s
[03:13:46.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:45:0)
[03:13:46.326] Timer tick, asking Buildbucket for the build status
[03:13:46.558] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:46.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:46:0) after 1m0s
[03:14:46.588] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:46:0)
[03:14:46.588] Timer tick, asking Buildbucket for the build status
[03:14:46.714] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:46.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:47:0) after 1m0s
[03:15:46.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:47:0)
[03:15:46.754] Timer tick, asking Buildbucket for the build status
[03:15:46.954] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:46.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:48:0) after 1m0s
[03:16:46.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:48:0)
[03:16:46.974] Timer tick, asking Buildbucket for the build status
[03:16:47.135] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:47.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:49:0) after 1m0s
[03:17:47.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:49:0)
[03:17:47.196] Timer tick, asking Buildbucket for the build status
[03:17:47.355] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:47.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:50:0) after 1m0s
[03:18:47.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:50:0)
[03:18:47.433] Timer tick, asking Buildbucket for the build status
[03:18:47.542] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:47.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:51:0) after 1m0s
[03:19:47.650] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:51:0)
[03:19:47.650] Timer tick, asking Buildbucket for the build status
[03:19:48.034] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:48.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:52:0) after 1m0s
[03:20:48.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:52:0)
[03:20:48.133] Timer tick, asking Buildbucket for the build status
[03:20:48.265] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:48.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:53:0) after 1m0s
[03:21:48.426] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:53:0)
[03:21:48.426] Timer tick, asking Buildbucket for the build status
[03:21:48.710] Build 8864507981635286272: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:48.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029938043684035520:54:0) after 1m0s
[03:22:09.470] Received PubSub notification, asking Buildbucket for the build status
[03:22:09.937] Build 8864507981635286272: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:22:09.937] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604546528020464",
"created_by": "project:chromium",
"created_ts": "1604543432544450",
"id": "8864507981635286272",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"f86023cac54e158577dbe0092a7b8e8c4e1654d9\"}}",
"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\": \"f86023cac54e158577dbe0092a7b8e8c4e1654d9\", \"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\": 15467, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"4989667a9c4c8f093a539ac45b9c93798e093890\", \"got_dawn_revision\": \"6564890116fd5629ad76948f12ad158d6f111cc3\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"f86023cac54e158577dbe0092a7b8e8c4e1654d9\", \"got_revision_cp\": \"refs/heads/master@{#824257}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"7ae0bc9f7258d206e0de93911c5987c3e04979b8\", \"got_v8_revision_cp\": \"refs/heads/8.8.228@{#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\": \"f86023cac54e158577dbe0092a7b8e8c4e1654d9\", \"swarm_hashes_refs/heads/master(at){#824257}_without_patch\": {\"absl_hardening_tests\": \"c579cf9eccf1410e2f757b1fcde431fa927e87db\", \"accessibility_unittests\": \"6fc7827205b11f71f28e0533b95ee102351a8b1b\", \"angle_unittests\": \"4ff5b343c98498761819bcd08a4d280cc52d5f80\", \"app_shell_unittests\": \"865978b37ff531c079e4b6e94e14c48ce22a0a57\", \"aura_unittests\": \"f5fe5dc8f631b0ed5671fc529d764b3c70d435dc\", \"base_unittests\": \"20349b072f72db69909161ff87e164f05dd07000\", \"base_util_unittests\": \"0c0c819cdf4d3759ae64a2ca4b35bb81dd3ccac8\", \"blink_common_unittests\": \"5d6162d8949114dc227e8a4c1cce757a1757fb07\", \"blink_fuzzer_unittests\": \"749a7310469519f699b8173935ea23e2b252c879\", \"blink_heap_unittests\": \"85f5750d3adb88944834a21faf43f8d7232eed3d\", \"blink_platform_unittests\": \"d069916e49774d018d16e933609ff2c7b730631e\", \"blink_unittests\": \"fd8da8975326a814af94e26bb3ea733a8ea00411\", \"boringssl_crypto_tests\": \"ac50efc9f2d8aa35c6c9394bb7416364463b3590\", \"boringssl_ssl_tests\": \"da5a1707c0c714032818baf1d34ef53fd54e3297\", \"browser_switcher_bho_unittests\": \"74c023527e602357538c652a70c8d14c3e1030b1\", \"browser_tests\": \"fbdd775fe634ca218f79b2d73f81c505ea8b2757\", \"capture_unittests\": \"af2b29b552888a4328415a6d479619a399cc7dfe\", \"cast_unittests\": \"c57334d24577a878d99f2ae268faf4323f8a29ab\", \"cc_unittests\": \"467df037dac4203d04bb7f8f526354d50623469c\", \"chrome_app_unittests\": \"690da6bf1bd6294080f763b20e8a8d1b62519982\", \"chrome_cleaner_unittests\": \"141fdf4344dcedd69f817f27365bc4429e40826d\", \"chrome_elf_unittests\": \"baf5afa9ac8975d254ba433be465a49334c4e987\", \"chromedriver_unittests\": \"6950429f4322831e57dfbcaad15aa95280ddea3a\", \"components_browsertests\": \"c0db90e739e95d6f285283ab9adb2666b1b2c744\", \"components_unittests\": \"f37620a4e44ebee2020899d3a8e7280dd2d5ee08\", \"compositor_unittests\": \"2cbb8e7827be006f02812c7702539eb2278144bc\", \"content_browsertests\": \"957c3eccf35ffbfde14848dc19de196bc2987c84\", \"content_unittests\": \"47d946b05b98152723d26d1699cf2bcb73e3b1cd\", \"courgette_unittests\": \"d7a82196ce78446f738f0cb51b36f09dc023ad30\", \"crashpad_tests\": \"5bac3dc10a8a7e617f8f7b2519a3e356e5df4542\", \"cronet_tests\": \"e6f23bfdaaa8a773fc320793605d67fc6f1af08d\", \"cronet_unittests\": \"9fbc96d9f524543ff880ea495753c2448919bfb4\", \"crypto_unittests\": \"61bffc5c07292bc4b68893f407b06409fc3682f7\", \"delayloads_unittests\": \"e054e03444470f8589d4847ae646bece289fc37e\", \"device_unittests\": \"96e2146be141b6c9f03eeeafa7d4a83a532d5660\", \"display_unittests\": \"40c8b7933247f7aadd6c358185be1b930a9327ee\", \"elevation_service_unittests\": \"66998e4d857e1c05d535add1fa657bb029dd00a7\", \"events_unittests\": \"7399f217508c3dfca41f0682d5c127f9562d3a89\", \"extensions_browsertests\": \"acea487ac56fda9b7c115d8a4484acc7b7292cd3\", \"extensions_unittests\": \"31032f80263c0027217cf9db27f6c8b26794a52d\", \"filesystem_service_unittests\": \"f0b56eadf6e5bb3c47bba51628d9b030769d67ab\", \"gcm_unit_tests\": \"4fda5997a27a445fdefaf4acd914d7caa154b8c7\", \"gcp_unittests\": \"679aa08724c76e14b2ab82efbd80f6a9501fa3de\", \"gfx_unittests\": \"f96c102fef3dae21348629a21cd9dd03fd92ae61\", \"gin_unittests\": \"12d586d0258b4e3866fe3608eacb12deeed82858\", \"google_apis_unittests\": \"28d82d9455c3fd24dbe43869dac6a05d8464c595\", \"gpu_unittests\": \"7bf9b176be28df3f5aeb0748c84d07a65c128a68\", \"gwp_asan_unittests\": \"bcab3c2fe02f43c50ef17bc364e2d3247b26c396\", \"headless_browsertests\": \"9cd5a6ecefcca4a35670708b80b9f33509002266\", \"headless_unittests\": \"5fac4a71a3902b92353ab3c1fb0fbec0a780d432\", \"install_static_unittests\": \"ccfb50573fc6c0b4517117ef36eb6a74114adc2e\", \"installer_util_unittests\": \"6adb351dff1d77c72a380eeb6e36b95161fdcef9\", \"interactive_ui_tests\": \"3d7c771c25a66ccae7cabc53d3e8069607f87258\", \"ipc_tests\": \"951dcf3ef37c9b86bc00b206d52a9ed135956295\", \"jingle_unittests\": \"2c58bef054b40053ca9c9f8f016568aeb36aee32\", \"latency_unittests\": \"ffd2542acd75c9af8ce7222dfbeed91946cecf22\", \"libjingle_xmpp_unittests\": \"e0bcb6c76f4d74e7c721929d8374739f297dae64\", \"liburlpattern_unittests\": \"b5944dcf0784f865fcfd12a24761a075004738a2\", \"media_blink_unittests\": \"18a3d38baf6a18781c02f17580785e50d927b19f\", \"media_unittests\": \"96c3714414708293a8a45e97ffef5dde19f8d9f2\", \"message_center_unittests\": \"da40d375a9c74f8b10730ef2740f44caf27ed441\", \"midi_unittests\": \"a1b2aa71e7703066d1e8977feb8baeb4d3b3b3fc\", \"mojo_core_unittests\": \"b17bf33030a7807c34d467485e1384c275f7926e\", \"mojo_unittests\": \"aaf5a5730a752aed13bfbf5cb9b25e04b8e3a47c\", \"nacl_loader_unittests\": \"c9d8f5730599897883aa8f0aa4075fa99c8f736b\", \"native_theme_unittests\": \"54dd1bc69be07c50659264a3ca1a7bd4f212db88\", \"net_unittests\": \"0449485f8c7a7006097e13f06432f0155ecd6805\", \"notification_helper_unittests\": \"931ece990bb5c36729fec7d7f52e61069e53c7f6\", \"pdf_unittests\": \"4dd08798a2ff48d4b6296f84ed5b84a13fcd5064\", \"perfetto_unittests\": \"cf381c31f2e864fb87f92012de2acdfece7ca4c9\", \"ppapi_unittests\": \"87ed53bad72674fa1e8d08c85e9f1a31a3efad45\", \"printing_unittests\": \"e722970416bafa2702da8f5cc47b0adfe7d5b88d\", \"remoting_unittests\": \"545e202293a332af37d0a6448dfbbf969d8fd5d2\", \"sbox_integration_tests\": \"510fba7d91280a37e9162b6cff7283723bce9c12\", \"sbox_unittests\": \"5e0505dd87bedb5371338e5c3b0648d6851ad3cd\", \"sbox_validation_tests\": \"58d896eff96535fe20999193167aedb2b7decd64\", \"service_manager_unittests\": \"33fcdba12298704289cdc41e056a0471bf92b2be\", \"services_unittests\": \"8fe1bd1ecfffc913c5f7661bda9bd04a26d2c08b\", \"setup_unittests\": \"8c3ba285d66a29de3010e10a52e73492c8656820\", \"shell_dialogs_unittests\": \"25aa952cb7d61d30afbbbc10d4b7b7e58a271916\", \"skia_unittests\": \"7f09dda39511d6609bfd6a05b850556b39a1a539\", \"snapshot_unittests\": \"ed52025d4a2cf16ceeb9e212f6bcdc106bdfd291\", \"sql_unittests\": \"81b8f72e2bfb60a9f9e971ba5d591c9f754f984b\", \"storage_unittests\": \"8e9af4449f2608b84f54ea71be2b6991cc286c51\", \"sync_integration_tests\": \"254d09340e82ea7d3466381228c4824c8f2f085b\", \"traffic_annotation_auditor_unittests\": \"37310f8a063e9a54974418e31ee8de012b841661\", \"ui_base_unittests\": \"7176cd5a7a587bc1605e5e63cbde868f37c3965e\", \"ui_touch_selection_unittests\": \"812fac0d0e8bdb3e52e2b8b10083cd4b5135972f\", \"unit_tests\": \"33ecd539a525a5d3a57edcfac0630003c617ecd4\", \"updater_tests\": \"5a6da123d90b97f64ed0f1562e6a90b2125161a1\", \"url_unittests\": \"d95b4c729f45e4b7c6db815037f851419f64f2c7\", \"views_unittests\": \"ad12ef3277f2b05d33ce7af95b54c079144a2024\", \"viz_unittests\": \"98920ec09ba117ff2a15ad38f85ec69496c43f62\", \"vr_common_unittests\": \"9ba18d99fff3ea1e923f05c2cf85a70cb2d82b40\", \"vr_pixeltests\": \"ca096c321160357bf21432e683a250d091411c05\", \"weblayer_browsertests\": \"988414128f9b88bed6ec11a757381f2c5b885894\", \"weblayer_unittests\": \"4bfa3546b791567e4ebf0b7506c86a0b6308b2fa\", \"wm_unittests\": \"a082e0911814040ef4a2cd4728df1f876a00b6d3\", \"wtf_unittests\": \"d8dd332bce057b0cb1c784254baf7f43df384d7a\", \"zlib_unittests\": \"53becd8bc685c18d75288e98730510d1c3af56c2\", \"zucchini_unittests\": \"0b6186f20859a4d2af745d6798dfc9c3ca6dca9e\"}}, \"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": "1604543435987981",
"status": "COMPLETED",
"status_changed_ts": "1604546528298314",
"tags": [
"build_address:luci.chromium.ci/win-asan/15467",
"builder:win-asan",
"buildset:commit/git/f86023cac54e158577dbe0092a7b8e8c4e1654d9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/f86023cac54e158577dbe0092a7b8e8c4e1654d9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029938043684035520",
"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/8864507981635286272/+/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:4faf0f57aecf0b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604546528306440",
"url": "https://ci.chromium.org/b/8864507981635286272",
"utcnow_ts": "1604546529907018"
}
}
[03:22:09.937] Invocation finished in 51m38.730622123s with status SUCCEEDED