[16:25:48.348] New invocation is queued and will start shortly
[16:25:49.614] Starting the invocation (attempt 1)
[16:25:49.693] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:25:49.694] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:25:49.694] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029432507237580416",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1ff97baf972a6c9f314d8160d1219f164495c83b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029432507237580416",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1ff97baf972a6c9f314d8160d1219f164495c83b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ff97baf972a6c9f314d8160d1219f164495c83b",
"gitiles_ref:refs/heads/master"
]
}
[16:25:51.039] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605025549803261",
"id": "8864002445047578400",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ff97baf972a6c9f314d8160d1219f164495c83b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605025550696253",
"tags": [
"build_address:luci.chromium.ci/win-asan/15574",
"builder:win-asan",
"buildset:commit/git/1ff97baf972a6c9f314d8160d1219f164495c83b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ff97baf972a6c9f314d8160d1219f164495c83b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029432507237580416",
"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/8864002445047578400/+/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": "1605025550696371",
"url": "https://ci.chromium.org/b/8864002445047578400",
"utcnow_ts": "1605025551022970"
}
}
[16:25:51.039] Task URL: https://ci.chromium.org/b/8864002445047578400
[16:25:51.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:2:0) after 1m0s
[16:25:55.007] Received PubSub notification, asking Buildbucket for the build status
[16:25:55.103] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:53.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:2:0)
[16:26:53.324] Timer tick, asking Buildbucket for the build status
[16:26:55.162] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:55.162] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:4:0) after 1m0s
[16:27:55.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:4:0)
[16:27:55.268] Timer tick, asking Buildbucket for the build status
[16:27:55.426] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:55.426] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:5:0) after 1m0s
[16:28:55.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:5:0)
[16:28:55.524] Timer tick, asking Buildbucket for the build status
[16:28:55.882] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:55.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:6:0) after 1m0s
[16:29:55.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:6:0)
[16:29:55.975] Timer tick, asking Buildbucket for the build status
[16:29:56.155] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:56.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:7:0) after 1m0s
[16:30:56.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:7:0)
[16:30:56.229] Timer tick, asking Buildbucket for the build status
[16:30:56.443] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:56.443] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:8:0) after 1m0s
[16:31:56.446] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:8:0)
[16:31:56.446] Timer tick, asking Buildbucket for the build status
[16:31:56.951] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:56.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:9:0) after 1m0s
[16:32:56.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:9:0)
[16:32:56.998] Timer tick, asking Buildbucket for the build status
[16:32:57.171] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:57.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:10:0) after 1m0s
[16:33:57.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:10:0)
[16:33:57.389] Timer tick, asking Buildbucket for the build status
[16:33:57.800] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:57.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:11:0) after 1m0s
[16:34:57.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:11:0)
[16:34:57.881] Timer tick, asking Buildbucket for the build status
[16:34:57.983] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:57.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:12:0) after 1m0s
[16:35:58.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:12:0)
[16:35:58.015] Timer tick, asking Buildbucket for the build status
[16:35:58.407] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:58.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:13:0) after 1m0s
[16:36:58.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:13:0)
[16:36:58.461] Timer tick, asking Buildbucket for the build status
[16:36:58.954] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:58.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:14:0) after 1m0s
[16:37:59.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:14:0)
[16:37:59.013] Timer tick, asking Buildbucket for the build status
[16:37:59.085] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:59.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:15:0) after 1m0s
[16:38:59.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:15:0)
[16:38:59.173] Timer tick, asking Buildbucket for the build status
[16:38:59.294] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:59.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:16:0) after 1m0s
[16:39:59.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:16:0)
[16:39:59.353] Timer tick, asking Buildbucket for the build status
[16:39:59.474] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:59.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:17:0) after 1m0s
[16:40:59.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:17:0)
[16:40:59.524] Timer tick, asking Buildbucket for the build status
[16:40:59.667] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:59.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:18:0) after 1m0s
[16:41:59.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:18:0)
[16:41:59.678] Timer tick, asking Buildbucket for the build status
[16:42:01.404] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:01.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:19:0) after 1m0s
[16:43:03.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:19:0)
[16:43:03.681] Timer tick, asking Buildbucket for the build status
[16:43:03.820] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:03.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:20:0) after 1m0s
[16:44:03.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:20:0)
[16:44:03.854] Timer tick, asking Buildbucket for the build status
[16:44:04.007] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:04.007] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:21:0) after 1m0s
[16:45:04.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:21:0)
[16:45:04.855] Timer tick, asking Buildbucket for the build status
[16:45:06.620] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:06.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:22:0) after 1m0s
[16:46:06.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:22:0)
[16:46:06.598] Timer tick, asking Buildbucket for the build status
[16:46:06.791] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:06.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:23:0) after 1m0s
[16:47:06.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:23:0)
[16:47:06.850] Timer tick, asking Buildbucket for the build status
[16:47:07.300] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:07.300] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:24:0) after 1m0s
[16:48:07.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:24:0)
[16:48:07.351] Timer tick, asking Buildbucket for the build status
[16:48:07.940] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:07.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:25:0) after 1m0s
[16:49:08.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:25:0)
[16:49:08.003] Timer tick, asking Buildbucket for the build status
[16:49:08.801] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:08.801] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:26:0) after 1m0s
[16:50:08.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:26:0)
[16:50:08.826] Timer tick, asking Buildbucket for the build status
[16:50:08.959] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:08.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:27:0) after 1m0s
[16:51:09.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:27:0)
[16:51:09.062] Timer tick, asking Buildbucket for the build status
[16:51:09.618] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:09.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:28:0) after 1m0s
[16:52:09.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:28:0)
[16:52:09.685] Timer tick, asking Buildbucket for the build status
[16:52:09.955] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:09.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:29:0) after 1m0s
[16:53:09.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:29:0)
[16:53:09.988] Timer tick, asking Buildbucket for the build status
[16:53:10.486] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:10.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:30:0) after 1m0s
[16:54:10.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:30:0)
[16:54:10.540] Timer tick, asking Buildbucket for the build status
[16:54:10.688] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:10.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:31:0) after 1m0s
[16:55:10.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:31:0)
[16:55:10.727] Timer tick, asking Buildbucket for the build status
[16:55:12.403] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:12.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:32:0) after 1m0s
[16:56:12.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:32:0)
[16:56:12.428] Timer tick, asking Buildbucket for the build status
[16:56:12.583] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:12.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:33:0) after 1m0s
[16:57:12.667] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:33:0)
[16:57:12.667] Timer tick, asking Buildbucket for the build status
[16:57:13.029] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:13.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:34:0) after 1m0s
[16:58:13.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:34:0)
[16:58:13.100] Timer tick, asking Buildbucket for the build status
[16:58:13.254] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:13.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:35:0) after 1m0s
[16:59:13.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:35:0)
[16:59:13.292] Timer tick, asking Buildbucket for the build status
[16:59:13.541] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:13.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:36:0) after 1m0s
[17:00:14.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:36:0)
[17:00:14.115] Timer tick, asking Buildbucket for the build status
[17:00:14.287] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:14.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:37:0) after 1m0s
[17:01:14.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:37:0)
[17:01:14.574] Timer tick, asking Buildbucket for the build status
[17:01:14.755] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:14.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:38:0) after 1m0s
[17:02:14.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:38:0)
[17:02:14.763] Timer tick, asking Buildbucket for the build status
[17:02:15.254] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:15.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:39:0) after 1m0s
[17:03:15.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:39:0)
[17:03:15.323] Timer tick, asking Buildbucket for the build status
[17:03:15.587] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:15.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:40:0) after 1m0s
[17:04:15.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:40:0)
[17:04:15.719] Timer tick, asking Buildbucket for the build status
[17:04:16.237] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:16.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:41:0) after 1m0s
[17:05:16.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:41:0)
[17:05:16.309] Timer tick, asking Buildbucket for the build status
[17:05:16.517] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:16.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:42:0) after 1m0s
[17:06:16.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:42:0)
[17:06:16.595] Timer tick, asking Buildbucket for the build status
[17:06:16.893] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:16.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:43:0) after 1m0s
[17:07:16.894] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:43:0)
[17:07:16.894] Timer tick, asking Buildbucket for the build status
[17:07:17.018] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:17.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:44:0) after 1m0s
[17:08:17.030] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:44:0)
[17:08:17.030] Timer tick, asking Buildbucket for the build status
[17:08:19.450] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:19.450] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:45:0) after 1m0s
[17:09:19.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:45:0)
[17:09:19.472] Timer tick, asking Buildbucket for the build status
[17:09:19.601] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:19.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:46:0) after 1m0s
[17:10:19.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:46:0)
[17:10:19.623] Timer tick, asking Buildbucket for the build status
[17:10:20.207] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:20.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:47:0) after 1m0s
[17:11:20.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:47:0)
[17:11:20.270] Timer tick, asking Buildbucket for the build status
[17:11:20.405] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:20.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:48:0) after 1m0s
[17:12:20.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:48:0)
[17:12:20.496] Timer tick, asking Buildbucket for the build status
[17:12:21.675] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:21.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:49:0) after 1m0s
[17:13:21.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:49:0)
[17:13:21.704] Timer tick, asking Buildbucket for the build status
[17:13:22.099] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:22.099] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:50:0) after 1m0s
[17:14:22.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:50:0)
[17:14:22.237] Timer tick, asking Buildbucket for the build status
[17:14:22.354] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:22.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:51:0) after 1m0s
[17:15:22.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:51:0)
[17:15:22.660] Timer tick, asking Buildbucket for the build status
[17:15:24.219] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:24.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:52:0) after 1m0s
[17:16:24.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:52:0)
[17:16:24.323] Timer tick, asking Buildbucket for the build status
[17:16:24.472] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:24.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:53:0) after 1m0s
[17:17:24.520] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:53:0)
[17:17:24.520] Timer tick, asking Buildbucket for the build status
[17:17:24.721] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:24.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:54:0) after 1m0s
[17:18:24.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:54:0)
[17:18:24.776] Timer tick, asking Buildbucket for the build status
[17:18:24.898] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:24.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:55:0) after 1m0s
[17:19:24.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:55:0)
[17:19:24.907] Timer tick, asking Buildbucket for the build status
[17:19:25.279] Build 8864002445047578400: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:25.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029432507237580416:56:0) after 1m0s
[17:19:59.526] Received PubSub notification, asking Buildbucket for the build status
[17:19:59.627] Build 8864002445047578400: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:19:59.627] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605028796700831",
"created_by": "project:chromium",
"created_ts": "1605025549803261",
"id": "8864002445047578400",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1ff97baf972a6c9f314d8160d1219f164495c83b\"}}",
"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\": \"1ff97baf972a6c9f314d8160d1219f164495c83b\", \"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-1-83xn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15574, \"clang_revision\": \"llvmorg-12-init-11060-g118c3f3c-1\", \"got_angle_revision\": \"a89c6b9a5c5bc51778ab53ace73cc0bb7ba581c9\", \"got_dawn_revision\": \"c04a0132be6c384c90bd33b06929a9b7fc5d4726\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"1ff97baf972a6c9f314d8160d1219f164495c83b\", \"got_revision_cp\": \"refs/heads/master@{#825822}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"4ed9d3498dcffa987acba1a8007ff8dec336f263\", \"got_v8_revision\": \"aee5f31f64a6abb79edd263271b4f1b0375241a5\", \"got_v8_revision_cp\": \"refs/heads/8.8.273@{#1}\", \"got_webrtc_revision\": \"9c99b7964fabb16a022293ffbd2804f9b46551f6\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32565}\", \"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\": \"1ff97baf972a6c9f314d8160d1219f164495c83b\", \"swarm_hashes_refs/heads/master(at){#825822}_without_patch\": {\"absl_hardening_tests\": \"91d2568880ae7a2c923d90852e1622753becd4bf\", \"accessibility_unittests\": \"2c96a0464b04d720962e5c224c4e12f27545f651\", \"angle_unittests\": \"c90cae349bc51000250725a81e553ab81fec4f38\", \"app_shell_unittests\": \"8f937fe0f6dcdf996604ab414f9ea8383e19b3aa\", \"aura_unittests\": \"bccd8fc0e31fa538ed5aea3d5ff28539a7970041\", \"base_unittests\": \"8549779231c3541ab01b71c76250f4af83684056\", \"base_util_unittests\": \"2948c49a68a8f721531b71d4beaf56d1ecf6f642\", \"blink_common_unittests\": \"4866f562aabb046d20fbc13be8fd3711d4da6767\", \"blink_fuzzer_unittests\": \"98257f767433ee5d4fdea84acb0ef3ab769280bf\", \"blink_heap_unittests\": \"e132e2af88291b61e87cbe55e420f73d82807ae6\", \"blink_platform_unittests\": \"6837f8accdde11e64ca128ca26cb1b27e76be7b0\", \"blink_unittests\": \"28c78151fdadeddffd4c881d096fe7bce3305098\", \"boringssl_crypto_tests\": \"a00b7b097efcdb5dc931a6bb782becbe83559b29\", \"boringssl_ssl_tests\": \"7ad452c3f8601ac680ce3c41445d130d65e9e2a4\", \"browser_switcher_bho_unittests\": \"b0fc7f67fc3e34177a10704dc1af875c9aca9851\", \"browser_tests\": \"5b5587f220311957fca3f58916986b8ef962f616\", \"capture_unittests\": \"6b62f539ddb2627c6943dd6b9156a612c7e2da49\", \"cast_unittests\": \"cab8e3eca8d75c72b0ff04716b81c3cf28d02ec7\", \"cc_unittests\": \"abf850294f81d073ffa0a0f94f37056b6ef24382\", \"chrome_app_unittests\": \"bf5b36b7bfba6ab7b3b81116a8cbb2eb862d6a6e\", \"chrome_cleaner_unittests\": \"3d80651a692e958ac0b5eff382fd390a882a1d97\", \"chrome_elf_unittests\": \"5dcbb29653ccaf574c489a711b30593d6879ec7d\", \"chromedriver_unittests\": \"f4e68d0f9042ec872ce35d1bf62ac9a4cd52895f\", \"components_browsertests\": \"ca2c43d9907c68f44b32f43aa2c1f50635d13b14\", \"components_unittests\": \"46ea31acabb5ed82e65367b82b0cda4fbade17c3\", \"compositor_unittests\": \"5de3d1fdb3124267843266ac9bbc744e9602fa6e\", \"content_browsertests\": \"4675a0699a7d023c67015b7b9e794bf52c2a594e\", \"content_unittests\": \"72eafc51135f8656399228c32f22ee375b1b3488\", \"courgette_unittests\": \"0e6fa713559b0a0ec7721263d6666d5dd48d345b\", \"crashpad_tests\": \"f6b9e57a085f843d9b83dcf8bacef314defa233a\", \"cronet_tests\": \"be21be79cc4c30b47a4869f8a6fd372a9a1bb58d\", \"cronet_unittests\": \"3a1e4c46cdb1b43482e71d235e2441ca99ebaee1\", \"crypto_unittests\": \"c9ae1cd642b4bea104913b85739877d9652153c9\", \"delayloads_unittests\": \"3bdfdfb553495802e8e36a979fb75632121853db\", \"device_unittests\": \"34b60f6508f4393150b910e78c65a99b9b72e61b\", \"display_unittests\": \"62f9c6bddadb2399620aea73d5114045e1d6f77d\", \"elevation_service_unittests\": \"645347eae5f8eaa6561b0bac15e4f3de46368e3d\", \"events_unittests\": \"7f6776619e88cc13adab41009631e79e1ea6dc62\", \"extensions_browsertests\": \"025965a909adf86b7d7bb9d266ac89b068ec9250\", \"extensions_unittests\": \"a2d3788ae226a74d71449271dfa05f7d5c6eee91\", \"filesystem_service_unittests\": \"72e784eea03e8bafe924be8429d4bc955f6aa893\", \"gcm_unit_tests\": \"205455ab2bd896ec61864de67693eee731638eff\", \"gcp_unittests\": \"33a34013da26832dc1ec9f292f5b296d1f9e29d6\", \"gfx_unittests\": \"290f4eb5db804dca370883247d52837bf51daf9e\", \"gin_unittests\": \"0cdb6f5d2a848fdcdcc7f90360f3f752459e2a93\", \"google_apis_unittests\": \"ee307689a86e7977c6db5a698df21ed1fad24c2a\", \"gpu_unittests\": \"7ef431feb1f54829954a81fc435010cf13668b8b\", \"gwp_asan_unittests\": \"3c63f3875333a54cb733c51f1ac80cfe7d377f51\", \"headless_browsertests\": \"ed9cbc1ced8d1a8868258216fb3c5b132f7b3a3c\", \"headless_unittests\": \"d1e20fb91911ffc08079ff9adcb92b46d7dd38ac\", \"install_static_unittests\": \"7a3e14821c6fcbbeec416ec5c8c120cd89cfbcc1\", \"installer_util_unittests\": \"c07218961d485edd4440d02753575e3e98f237ae\", \"interactive_ui_tests\": \"d66566b33b2386550db1d331339d11166349d6a8\", \"ipc_tests\": \"f3ea4f1483000c91812fd3f0fc48ad5ef6cb6179\", \"jingle_unittests\": \"cc18d55d856c2c5be50a53737334287f6d7930e2\", \"latency_unittests\": \"e2850b46ff924463432777081c5e90b649f8b689\", \"libjingle_xmpp_unittests\": \"08ba05953ad588d86b44225d9cde0e107c6289d6\", \"liburlpattern_unittests\": \"227051b31657b9a092d9edeffeebbce7b8156191\", \"media_blink_unittests\": \"c735f7d2f126c2112be40f53b8be6e6f8387869f\", \"media_unittests\": \"1fc23ddfbf321c84713a546877d4aa5efccbe089\", \"message_center_unittests\": \"f990947a0813c2336775f33c7c0917a9107987ce\", \"midi_unittests\": \"8ec04979f0848ba3b22eebeb066b85cedd04e551\", \"mojo_core_unittests\": \"29035d25deac1d43b5508b5732a6a36cb49016aa\", \"mojo_unittests\": \"fb9adc01f0f3370ad8b153fe558d464c27fcb91d\", \"nacl_loader_unittests\": \"f18ef1888f0e2a1916efe0b79a2257f5fe0f491b\", \"native_theme_unittests\": \"07ad0d23e4a4bef6f8e130cd02b83368186c604b\", \"net_unittests\": \"ef2c51c554b174188e2a300a03c017b8de64bef4\", \"notification_helper_unittests\": \"7f3cb7b28c171947392f4ccf831da58e2a4875e7\", \"pdf_unittests\": \"53ffd4c07467cd472cf1cef81f800976af51e07e\", \"perfetto_unittests\": \"66b276f971392b8af46ae333c9ed256f23d0ea8a\", \"ppapi_unittests\": \"27c5eab4f6e83f9b77e49456dde3bf333a26a0cf\", \"printing_unittests\": \"21a8c182a9ceea591b715a0d94ec1b0277633e2b\", \"remoting_unittests\": \"6847713a01b2943b9c96c3acd40bd7ad84b1c629\", \"sbox_integration_tests\": \"b3d49a54f524708f6c7b5650ec74adc6ee05b720\", \"sbox_unittests\": \"c2f6b1c1eb34ecf7112f337948ce1f343e8ee884\", \"sbox_validation_tests\": \"805bfb4114ce0b71e3bbdd69abafe6d5f29352f2\", \"service_manager_unittests\": \"2480728f5151bb4d3789d20c4a14ae63cf6e9c99\", \"services_unittests\": \"c9a669824bd826a36dd9db1847d9eca521e866df\", \"setup_unittests\": \"3076020f363c450dd70905bb50306ae21ab26144\", \"shell_dialogs_unittests\": \"94164145bf3458d9bb3cb31f396dc6752436f2c9\", \"skia_unittests\": \"11a42456883ea43776cc565390aa3e176c1c898a\", \"snapshot_unittests\": \"3d7ced9a0a7d3ad1ac5fecf201404a14b7e2fad6\", \"sql_unittests\": \"741e2872d1381bc74d3985fd8f633f14d56990f4\", \"storage_unittests\": \"6e099be33aee1748e855a25c05416a18e653b9fa\", \"sync_integration_tests\": \"bc5d01a7b7db9168cb72e507f59863b517180882\", \"traffic_annotation_auditor_unittests\": \"a5c913acb0bb9cd2bd45ec19182a530b7b03f45b\", \"ui_base_unittests\": \"dda042f7f9604bc47436149b7c2f01ce5a5af95d\", \"ui_touch_selection_unittests\": \"b5bb20587fbfb51f669d3698d8a83f2547729fa4\", \"unit_tests\": \"0d69d9f78cb20b001bf18ec3354bbc813e4c749b\", \"updater_tests\": \"83532553095c2ada5d01d9a68e59f9e612bf4af2\", \"url_unittests\": \"be3170b9f6937e09894d911fb6138ea5a2dd59c4\", \"views_unittests\": \"a03d53fe878158cac35a5dde6cf39dbaf4185777\", \"viz_unittests\": \"c55b58e1976b1e49cb59e99100cba560f1553ef4\", \"vr_common_unittests\": \"ae797d544752926aeec434627162c8fd28d38566\", \"vr_pixeltests\": \"78f82c899b1d16d59873c73d2b98fe5ff3f2067c\", \"weblayer_browsertests\": \"7c228aff113ad563f3d63eb132914399574cae63\", \"weblayer_unittests\": \"a690ac5fbf3b7cad0aa34db5d08d0dbd5b3002f7\", \"wm_unittests\": \"5483a6c5fa5bdd8b555597c07013f3064edd546e\", \"wtf_unittests\": \"b5f46d9ad5ed629428fc235d3810e162544d98c8\", \"zlib_unittests\": \"28e1a4660cf0e541eb58f54d81beae1722b4f3f6\", \"zucchini_unittests\": \"54fab35dec5afc37ba2b55e52a09448ace1e968a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_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-1-83xn\"], \"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\": [\"5626-39642e9\"], \"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": "1605025553368376",
"status": "COMPLETED",
"status_changed_ts": "1605028797363397",
"tags": [
"build_address:luci.chromium.ci/win-asan/15574",
"builder:win-asan",
"buildset:commit/git/1ff97baf972a6c9f314d8160d1219f164495c83b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1ff97baf972a6c9f314d8160d1219f164495c83b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029432507237580416",
"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/8864002445047578400/+/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:4fcbcbdac2afde10",
"user_agent:luci-scheduler"
],
"updated_ts": "1605028797440691",
"url": "https://ci.chromium.org/b/8864002445047578400",
"utcnow_ts": "1605028799605861"
}
}
[17:19:59.627] Invocation finished in 54m11.295342514s with status SUCCEEDED