[22:47:43.626] New invocation is queued and will start shortly
[22:47:44.900] Starting the invocation (attempt 1)
[22:47:44.944] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:47:44.945] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:47:44.945] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026599972867724928",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"73de442e6c86ae857d61b0586a571a79d865d1b2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026599972867724928",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/73de442e6c86ae857d61b0586a571a79d865d1b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/73de442e6c86ae857d61b0586a571a79d865d1b2",
"gitiles_ref:refs/heads/master"
]
}
[22:47:46.945] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607726865130225",
"id": "8861169910627595376",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"73de442e6c86ae857d61b0586a571a79d865d1b2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607726866534791",
"tags": [
"build_address:luci.chromium.ci/win-asan/16167",
"builder:win-asan",
"buildset:commit/git/73de442e6c86ae857d61b0586a571a79d865d1b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/73de442e6c86ae857d61b0586a571a79d865d1b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026599972867724928",
"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/8861169910627595376/+/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": "1607726866534969",
"url": "https://ci.chromium.org/b/8861169910627595376",
"utcnow_ts": "1607726866931219"
}
}
[22:47:46.945] Task URL: https://ci.chromium.org/b/8861169910627595376
[22:47:46.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:2:0) after 1m0s
[22:47:49.831] Received PubSub notification, asking Buildbucket for the build status
[22:47:50.580] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:46.961] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:2:0)
[22:48:46.961] Timer tick, asking Buildbucket for the build status
[22:48:47.066] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:48:47.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:4:0) after 1m0s
[22:49:47.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:4:0)
[22:49:47.111] Timer tick, asking Buildbucket for the build status
[22:49:47.261] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:49:47.261] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:5:0) after 1m0s
[22:50:47.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:5:0)
[22:50:47.282] Timer tick, asking Buildbucket for the build status
[22:50:47.444] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:50:47.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:6:0) after 1m0s
[22:51:47.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:6:0)
[22:51:47.461] Timer tick, asking Buildbucket for the build status
[22:51:47.904] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:51:47.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:7:0) after 1m0s
[22:52:47.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:7:0)
[22:52:47.903] Timer tick, asking Buildbucket for the build status
[22:52:48.700] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:52:48.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:8:0) after 1m0s
[22:53:48.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:8:0)
[22:53:48.798] Timer tick, asking Buildbucket for the build status
[22:53:49.280] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:53:49.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:9:0) after 1m0s
[22:54:49.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:9:0)
[22:54:49.291] Timer tick, asking Buildbucket for the build status
[22:54:49.361] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:54:49.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:10:0) after 1m0s
[22:55:49.382] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:10:0)
[22:55:49.382] Timer tick, asking Buildbucket for the build status
[22:55:49.533] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:55:49.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:11:0) after 1m0s
[22:56:49.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:11:0)
[22:56:49.553] Timer tick, asking Buildbucket for the build status
[22:56:49.629] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:56:49.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:12:0) after 1m0s
[22:57:49.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:12:0)
[22:57:49.648] Timer tick, asking Buildbucket for the build status
[22:57:50.025] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:57:50.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:13:0) after 1m0s
[22:58:50.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:13:0)
[22:58:50.067] Timer tick, asking Buildbucket for the build status
[22:58:50.150] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:58:50.150] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:14:0) after 1m0s
[22:59:50.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:14:0)
[22:59:50.197] Timer tick, asking Buildbucket for the build status
[22:59:50.265] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:59:50.266] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:15:0) after 1m0s
[23:00:50.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:15:0)
[23:00:50.261] Timer tick, asking Buildbucket for the build status
[23:00:50.336] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:00:50.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:16:0) after 1m0s
[23:01:50.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:16:0)
[23:01:50.370] Timer tick, asking Buildbucket for the build status
[23:01:50.540] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:01:50.540] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:17:0) after 1m0s
[23:02:50.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:17:0)
[23:02:50.540] Timer tick, asking Buildbucket for the build status
[23:02:50.688] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:02:50.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:18:0) after 1m0s
[23:03:50.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:18:0)
[23:03:50.706] Timer tick, asking Buildbucket for the build status
[23:03:50.805] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:03:50.805] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:19:0) after 1m0s
[23:04:50.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:19:0)
[23:04:50.856] Timer tick, asking Buildbucket for the build status
[23:04:51.322] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:04:51.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:20:0) after 1m0s
[23:05:51.341] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:20:0)
[23:05:51.341] Timer tick, asking Buildbucket for the build status
[23:05:51.395] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:05:51.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:21:0) after 1m0s
[23:06:51.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:21:0)
[23:06:51.416] Timer tick, asking Buildbucket for the build status
[23:06:51.527] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:06:51.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:22:0) after 1m0s
[23:07:51.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:22:0)
[23:07:51.549] Timer tick, asking Buildbucket for the build status
[23:07:51.911] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:07:51.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:23:0) after 1m0s
[23:08:51.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:23:0)
[23:08:51.930] Timer tick, asking Buildbucket for the build status
[23:08:52.056] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:08:52.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:24:0) after 1m0s
[23:09:52.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:24:0)
[23:09:52.044] Timer tick, asking Buildbucket for the build status
[23:09:52.178] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:09:52.178] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:25:0) after 1m0s
[23:10:52.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:25:0)
[23:10:52.186] Timer tick, asking Buildbucket for the build status
[23:10:52.620] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:52.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:26:0) after 1m0s
[23:11:52.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:26:0)
[23:11:52.642] Timer tick, asking Buildbucket for the build status
[23:11:52.794] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:52.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:27:0) after 1m0s
[23:12:52.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:27:0)
[23:12:52.771] Timer tick, asking Buildbucket for the build status
[23:12:52.899] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:52.899] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:28:0) after 1m0s
[23:13:52.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:28:0)
[23:13:52.924] Timer tick, asking Buildbucket for the build status
[23:13:53.031] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:53.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:29:0) after 1m0s
[23:14:53.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:29:0)
[23:14:53.072] Timer tick, asking Buildbucket for the build status
[23:14:53.179] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:53.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:30:0) after 1m0s
[23:15:53.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:30:0)
[23:15:53.194] Timer tick, asking Buildbucket for the build status
[23:15:53.645] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:53.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:31:0) after 1m0s
[23:16:53.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:31:0)
[23:16:53.653] Timer tick, asking Buildbucket for the build status
[23:16:53.750] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:53.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:32:0) after 1m0s
[23:17:53.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:32:0)
[23:17:53.795] Timer tick, asking Buildbucket for the build status
[23:17:53.912] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:53.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:33:0) after 1m0s
[23:18:53.932] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:33:0)
[23:18:53.932] Timer tick, asking Buildbucket for the build status
[23:18:54.077] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:54.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:34:0) after 1m0s
[23:19:54.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:34:0)
[23:19:54.097] Timer tick, asking Buildbucket for the build status
[23:19:55.326] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:55.326] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:35:0) after 1m0s
[23:20:55.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:35:0)
[23:20:55.422] Timer tick, asking Buildbucket for the build status
[23:20:55.533] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:55.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:36:0) after 1m0s
[23:21:55.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:36:0)
[23:21:55.635] Timer tick, asking Buildbucket for the build status
[23:21:55.712] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:55.712] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:37:0) after 1m0s
[23:22:55.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:37:0)
[23:22:55.810] Timer tick, asking Buildbucket for the build status
[23:22:55.936] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:55.936] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:38:0) after 1m0s
[23:23:55.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:38:0)
[23:23:55.978] Timer tick, asking Buildbucket for the build status
[23:23:56.058] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:56.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:39:0) after 1m0s
[23:24:56.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:39:0)
[23:24:56.422] Timer tick, asking Buildbucket for the build status
[23:24:56.720] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:56.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:40:0) after 1m0s
[23:25:56.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:40:0)
[23:25:56.783] Timer tick, asking Buildbucket for the build status
[23:25:56.894] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:56.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:41:0) after 1m0s
[23:26:57.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:41:0)
[23:26:57.126] Timer tick, asking Buildbucket for the build status
[23:26:57.237] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:57.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:42:0) after 1m0s
[23:27:57.289] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:42:0)
[23:27:57.289] Timer tick, asking Buildbucket for the build status
[23:27:57.396] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:57.396] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:43:0) after 1m0s
[23:28:57.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:43:0)
[23:28:57.584] Timer tick, asking Buildbucket for the build status
[23:28:57.705] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:57.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:44:0) after 1m0s
[23:29:57.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:44:0)
[23:29:57.918] Timer tick, asking Buildbucket for the build status
[23:29:57.999] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:57.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:45:0) after 1m0s
[23:30:58.200] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:45:0)
[23:30:58.200] Timer tick, asking Buildbucket for the build status
[23:30:58.276] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:58.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:46:0) after 1m0s
[23:31:58.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:46:0)
[23:31:58.383] Timer tick, asking Buildbucket for the build status
[23:31:58.605] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:58.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:47:0) after 1m0s
[23:32:58.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:47:0)
[23:32:58.714] Timer tick, asking Buildbucket for the build status
[23:32:59.731] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:59.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:48:0) after 1m0s
[23:33:59.790] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:48:0)
[23:33:59.790] Timer tick, asking Buildbucket for the build status
[23:33:59.980] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:59.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:49:0) after 1m0s
[23:35:00.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:49:0)
[23:35:00.007] Timer tick, asking Buildbucket for the build status
[23:35:00.295] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:00.295] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:50:0) after 1m0s
[23:36:00.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:50:0)
[23:36:00.418] Timer tick, asking Buildbucket for the build status
[23:36:00.514] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:00.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:51:0) after 1m0s
[23:37:01.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:51:0)
[23:37:01.598] Timer tick, asking Buildbucket for the build status
[23:37:02.244] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:02.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:52:0) after 1m0s
[23:38:02.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:52:0)
[23:38:02.779] Timer tick, asking Buildbucket for the build status
[23:38:03.169] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:03.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:53:0) after 1m0s
[23:39:03.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:53:0)
[23:39:03.268] Timer tick, asking Buildbucket for the build status
[23:39:03.351] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:03.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:54:0) after 1m0s
[23:40:03.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:54:0)
[23:40:03.368] Timer tick, asking Buildbucket for the build status
[23:40:03.514] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:03.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:55:0) after 1m0s
[23:41:03.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:55:0)
[23:41:03.656] Timer tick, asking Buildbucket for the build status
[23:41:03.796] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:03.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:56:0) after 1m0s
[23:42:04.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:56:0)
[23:42:04.103] Timer tick, asking Buildbucket for the build status
[23:42:04.475] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:04.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:57:0) after 1m0s
[23:43:04.600] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:57:0)
[23:43:04.600] Timer tick, asking Buildbucket for the build status
[23:43:04.824] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:04.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:58:0) after 1m0s
[23:44:04.917] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:58:0)
[23:44:04.917] Timer tick, asking Buildbucket for the build status
[23:44:05.152] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:05.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:59:0) after 1m0s
[23:45:05.191] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:59:0)
[23:45:05.191] Timer tick, asking Buildbucket for the build status
[23:45:05.361] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:05.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:60:0) after 1m0s
[23:46:05.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:60:0)
[23:46:05.428] Timer tick, asking Buildbucket for the build status
[23:46:05.511] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:05.511] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:61:0) after 1m0s
[23:47:05.537] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:61:0)
[23:47:05.537] Timer tick, asking Buildbucket for the build status
[23:47:05.690] Build 8861169910627595376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:05.690] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026599972867724928:62:0) after 1m0s
[23:47:57.029] Received PubSub notification, asking Buildbucket for the build status
[23:47:57.107] Build 8861169910627595376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[23:47:57.107] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607730476198116",
"created_by": "project:chromium",
"created_ts": "1607726865130225",
"id": "8861169910627595376",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"73de442e6c86ae857d61b0586a571a79d865d1b2\"}}",
"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\": \"73de442e6c86ae857d61b0586a571a79d865d1b2\", \"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-3-0bya\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16167, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"d9318acc24b0b0f870b61ca82c8b7b517e7c5385\", \"got_dawn_revision\": \"ed883bc1d977e3c87579f206e094f36d443e1cfe\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"73de442e6c86ae857d61b0586a571a79d865d1b2\", \"got_revision_cp\": \"refs/heads/master@{#836291}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"31cc1f148e429a812538ef863da95f9f0d261680\", \"got_v8_revision\": \"84a26597476fcb63f1e724fd56b4f855c87a7c84\", \"got_v8_revision_cp\": \"refs/heads/8.9.138@{#1}\", \"got_webrtc_revision\": \"d7808f1c464a07c8f1e2f97ec7ee92fda998d590\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32804}\", \"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\": \"73de442e6c86ae857d61b0586a571a79d865d1b2\", \"swarm_hashes_refs/heads/master(at){#836291}_without_patch\": {\"absl_hardening_tests\": \"d96d6dea504c58c0545aeda51823320254914187\", \"accessibility_unittests\": \"3f6428a23a1ece337b8549078567b9e27dd01eb3\", \"angle_unittests\": \"8d731621f9f6ba099b1e9b0980f8380361cf7651\", \"app_shell_unittests\": \"251c9eaa512ab0259550256a1c07804cba7c33dc\", \"aura_unittests\": \"97bc3085437d66f08a4f9909ff4c84a0bc04e9ab\", \"base_unittests\": \"c6fe80800fe53b89b2a1112558640ada8e02fdb3\", \"base_util_unittests\": \"f434e7af26e90a8d6790b29cc22a5fa9c148a3ba\", \"blink_common_unittests\": \"662eb00cb69098528673dd74736afc78bf151d23\", \"blink_fuzzer_unittests\": \"b7427785fc521b9ad619c6f00a042cc640eb6811\", \"blink_heap_unittests\": \"1472c4bda6bdaf1d7abac47d23234aca38e2a586\", \"blink_platform_unittests\": \"364fede471564dd59f5fab1dbd7643f20dd49a97\", \"blink_unittests\": \"96e1f3f0d7c992cd9c6f6cd6a86f89ee55c1d855\", \"boringssl_crypto_tests\": \"d68e2cbfab7a8505397717fce453a1550a24c333\", \"boringssl_ssl_tests\": \"2796338b158dd0174d186c42117359edf8046a42\", \"browser_switcher_bho_unittests\": \"d2b1055df34552acc107d2cf5c265440c5caa830\", \"browser_tests\": \"3c6eb81e9073d96b19f41328d965f46457da1c5c\", \"capture_unittests\": \"cd998cdf44b3181e639eabe708fafc94e08d30b4\", \"cast_unittests\": \"1aa4add20f926fa454aebb42f50b091b3b876606\", \"cc_unittests\": \"3640fb48b97f4af0f66ce4acee571cf30ce8aca9\", \"chrome_app_unittests\": \"42f07c9f224b0f37de6e3f88054601ed2bdaf9ba\", \"chrome_cleaner_unittests\": \"1d8c3f96e9a5ad705fde9f9d5bf3b533dd8803e7\", \"chrome_elf_unittests\": \"76c8b1f00f02c8fffff16175ce216b4e9198a2e8\", \"chromedriver_unittests\": \"37d5f464966b68eed687ae925c8084032da008dc\", \"components_browsertests\": \"1a0792b642e1628573e9dfdcaa796d2b8d95093e\", \"components_unittests\": \"b0d1fd1934d180a4e5edf5b909112b0323d90d57\", \"compositor_unittests\": \"afce95eb394fca729f338cb442215205682dc58b\", \"content_browsertests\": \"3f07ff0953e1070a5feb17ce0adaed0737a27ee7\", \"content_unittests\": \"27872980f5f6fbb172fd07c88e13a2dfc484b8d4\", \"courgette_unittests\": \"79409684d159d0c202335f295bac988cb6e815f2\", \"crashpad_tests\": \"1aea0992d4ed0d4578e8ba5835e1d8eaef4aa60b\", \"cronet_tests\": \"6c61a4126777e8a8cd3f82423230ca4b6e7ec6e5\", \"cronet_unittests\": \"1710255a3b29936e47807b92601eb0345c43aa61\", \"crypto_unittests\": \"a24496cd63fda23c17cb6fbaef2ec4cb3b32ee7d\", \"delayloads_unittests\": \"bf974d667f25ca496b2fe43c578de8f120850231\", \"device_unittests\": \"e62d706e23c35d7f3d44be818922508a8e03e97b\", \"display_unittests\": \"154aab3ba165a207479b098f23e187bc968d1c71\", \"elevation_service_unittests\": \"3ceb9b0fd477f76b05c2360ed946d6ab2c9a429e\", \"events_unittests\": \"00e4214a4984789a6b6fdb26aadecce328ea2eb4\", \"extensions_browsertests\": \"223cbcc43c9d880ddd776bb63c942a15bfd7d450\", \"extensions_unittests\": \"55381a128979e339fd131df7833d3046a33c0a94\", \"filesystem_service_unittests\": \"e4626939a52cda9269dbaee986855b991f61cd82\", \"gcm_unit_tests\": \"973a9d0fe53372a37f903a0aded884b48e1605ec\", \"gcp_unittests\": \"6f9a0351c8e821a877b704ca4b9f9ac81340ac30\", \"gfx_unittests\": \"0fc24e1cdb40424b4598aa00990c9ccd89146530\", \"gin_unittests\": \"5e425fe6e3e80b8c34a202025ec2d929bb10c5a1\", \"google_apis_unittests\": \"07cd650f76451e01c26006beded63f37a5f2b9f4\", \"gpu_unittests\": \"d459a8a6483b22ae471b7a0b2cacc37b81e973e9\", \"gwp_asan_unittests\": \"80712517001b67cedaa964aed998f2e0f6ff6c25\", \"headless_browsertests\": \"84db25446be5f50cbd0f12f08801548508e41d51\", \"headless_unittests\": \"7d73bc0f2954d5433594be112cec38737b901756\", \"install_static_unittests\": \"4275e7e33063334cd7833c4f8bf5c6212608f7d4\", \"installer_util_unittests\": \"361fe3418a98f2a1346f6f20ad2a81b60ae17f0b\", \"interactive_ui_tests\": \"e5dbd53e1188be982c059f3e411c7a56a183dfe2\", \"ipc_tests\": \"eba5da2c6aa6733a21732768024447172a59301b\", \"jingle_unittests\": \"ea02c05271895bbdf03a5845eb4814cc3ce1abd3\", \"latency_unittests\": \"5b012be38509fc1018baeb756e40655d9a90817e\", \"libjingle_xmpp_unittests\": \"4af2b1f7d14c0d0e9fddcb11cb3d8432d47b0c4a\", \"liburlpattern_unittests\": \"6b6e489d8f24c802c2d670d06f7fa2b89d1db30d\", \"media_blink_unittests\": \"13812673e0420e4451c8872959557badf6fc56c6\", \"media_unittests\": \"93a1ea38b5bdbbb3620a5dfe0f9d521480a79605\", \"message_center_unittests\": \"29f3cd0ce32b9c87b2032bdf08939ccd7af8d394\", \"midi_unittests\": \"fafae0114045c5d60d90b393d85466aa60552810\", \"mojo_core_unittests\": \"e3fdb01bcc9ccda4bcffc1d8f2f4d029caeee633\", \"mojo_unittests\": \"a73414de79f3ed1d9bbf704c1e70ada7f0254182\", \"nacl_loader_unittests\": \"88a3374430334895f7c61e0808909ad75f8ebdfc\", \"native_theme_unittests\": \"87b7b4a93ecd4a77f30e8bffe89d0b586e511bbe\", \"net_unittests\": \"4f678b32abd9c18c9a3de114f4a785d97977e5b1\", \"notification_helper_unittests\": \"09b6da9f74a4ad57bfc76434d1c6e5378a759d99\", \"pdf_unittests\": \"24ddca7e03b3e95392eb8e03f61e7b655fda4bb7\", \"perfetto_unittests\": \"205e1123bf1646402a1285aed81ad68879abf490\", \"ppapi_unittests\": \"1b71a13a9928a8e7840b8edf452de44537abbb0d\", \"printing_unittests\": \"83f4fbfd61fc81ddca9f8ec75b31a24cb48bd8ad\", \"remoting_unittests\": \"8add9e7ae7c7a9819d093a007fbd0b49a583a747\", \"sbox_integration_tests\": \"d38cd9f9429dbdc0b1fac3db04e39a58ce68bdbb\", \"sbox_unittests\": \"814c307aa10ecf3138eb775e412ba23d6374d93e\", \"sbox_validation_tests\": \"6a4580a91b6eca0fabc23f36e33e36ef650b773f\", \"service_manager_unittests\": \"6ee9a01ee373e590ce24777ba232bcbf3e12bf22\", \"services_unittests\": \"34d0da47b60343695a5549b30c7cc6b7497b56b7\", \"setup_unittests\": \"ebb1001ff8f05a874484b9460126b761b88ddcf3\", \"shell_dialogs_unittests\": \"74d3a77736fa793acf4be55ce18ab2a182491141\", \"skia_unittests\": \"543ce8a72bc8162a6a0e5cb9af8107d2375fdcd5\", \"snapshot_unittests\": \"a23f621ef845207decfac9397561922d6ce13e3b\", \"sql_unittests\": \"f06754c4bd8213eb5747bffe481f500201b08d73\", \"storage_unittests\": \"c01f99edc7ea8e725f083a2ae246ff886000c24c\", \"sync_integration_tests\": \"5c22e032de00b7260a23edb8362a59cc2222c91b\", \"traffic_annotation_auditor_unittests\": \"de01e0f0ae4a4ee8d8d7be8e9c2be1b230743215\", \"ui_base_unittests\": \"7f2527dd7db4607d26e6064f3a322acfb556762c\", \"ui_touch_selection_unittests\": \"fa44fe586baf46aaf4beb3cff70d02c9eaf9cf8f\", \"unit_tests\": \"382b54450166cbaef081f7c04ee29e1d9e10a2bd\", \"updater_tests\": \"5252b26774c3bd9bfc14b9b8a80b268866b8f279\", \"url_unittests\": \"cbbb71d8ae6e84232f729efc960dc9e00a9d927c\", \"views_unittests\": \"a63b397e389a48322bdb33e1a6ff758e5b7dd7d5\", \"viz_unittests\": \"04481ea1bdcd01c9490d13e2ba2bc274cf864bb3\", \"vr_common_unittests\": \"63b655b9118390d6ecdbcc90932329e591c18178\", \"vr_pixeltests\": \"dbe11bf45ba0abd8a93f2be053cb61aede615806\", \"weblayer_browsertests\": \"0f98cedef92efddc44e789b83c0c4e5872391f1b\", \"weblayer_unittests\": \"3ba5371fe914640cb9abf79fb4083f68c75b0336\", \"wm_unittests\": \"4d6cb900408af429c865896be5253c9e60557efe\", \"wtf_unittests\": \"fc4506e43c0f2f67d2006f9807859721f9ab4822\", \"zlib_unittests\": \"fd972a216a3820c7239895d6e34968c2b30125c6\", \"zucchini_unittests\": \"b0323be8ecaa8cc4eae3977e8753b210bb8f6305\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_64c7a62a02e0da5ef76200bb7658267e40edf0984fe7c0f995e5999242969052_v2\", \"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-3-0bya\"], \"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": "1607726868846841",
"status": "COMPLETED",
"status_changed_ts": "1607730476593111",
"tags": [
"build_address:luci.chromium.ci/win-asan/16167",
"builder:win-asan",
"buildset:commit/git/73de442e6c86ae857d61b0586a571a79d865d1b2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/73de442e6c86ae857d61b0586a571a79d865d1b2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026599972867724928",
"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/8861169910627595376/+/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:506cceac8f819f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1607730476602931",
"url": "https://ci.chromium.org/b/8861169910627595376",
"utcnow_ts": "1607730477098180"
}
}
[23:47:57.107] Invocation finished in 1h0m13.497281851s with status SUCCEEDED