[02:13:06.517] New invocation is queued and will start shortly
[02:13:07.607] Starting the invocation (attempt 1)
[02:13:07.640] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[02:13:07.640] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[02:13:07.640] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9026315260479803776",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b9bb85d82a0449e5d55879a81f2424ef99346dc7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9026315260479803776",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b9bb85d82a0449e5d55879a81f2424ef99346dc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9bb85d82a0449e5d55879a81f2424ef99346dc7",
"gitiles_ref:refs/heads/master"
]
}
[02:13:08.488] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607998387807977",
"id": "8860885198464799168",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9bb85d82a0449e5d55879a81f2424ef99346dc7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607998388179176",
"tags": [
"build_address:luci.chromium.ci/win-asan/16230",
"builder:win-asan",
"buildset:commit/git/b9bb85d82a0449e5d55879a81f2424ef99346dc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9bb85d82a0449e5d55879a81f2424ef99346dc7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026315260479803776",
"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/8860885198464799168/+/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": "1607998388179360",
"url": "https://ci.chromium.org/b/8860885198464799168",
"utcnow_ts": "1607998388476927"
}
}
[02:13:08.488] Task URL: https://ci.chromium.org/b/8860885198464799168
[02:13:08.488] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:2:0) after 1m0s
[02:13:11.738] Received PubSub notification, asking Buildbucket for the build status
[02:13:11.811] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:08.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:2:0)
[02:14:08.561] Timer tick, asking Buildbucket for the build status
[02:14:08.676] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:14:08.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:4:0) after 1m0s
[02:15:08.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:4:0)
[02:15:08.727] Timer tick, asking Buildbucket for the build status
[02:15:08.841] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:15:08.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:5:0) after 1m0s
[02:16:08.885] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:5:0)
[02:16:08.886] Timer tick, asking Buildbucket for the build status
[02:16:09.029] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:16:09.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:6:0) after 1m0s
[02:17:09.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:6:0)
[02:17:09.075] Timer tick, asking Buildbucket for the build status
[02:17:09.546] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:17:09.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:7:0) after 1m0s
[02:18:09.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:7:0)
[02:18:09.626] Timer tick, asking Buildbucket for the build status
[02:18:09.715] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:18:09.715] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:8:0) after 1m0s
[02:19:09.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:8:0)
[02:19:09.776] Timer tick, asking Buildbucket for the build status
[02:19:10.358] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:19:10.358] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:9:0) after 1m0s
[02:20:10.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:9:0)
[02:20:10.337] Timer tick, asking Buildbucket for the build status
[02:20:10.409] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:20:10.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:10:0) after 1m0s
[02:21:10.428] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:10:0)
[02:21:10.428] Timer tick, asking Buildbucket for the build status
[02:21:10.610] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:21:10.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:11:0) after 1m0s
[02:22:10.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:11:0)
[02:22:10.649] Timer tick, asking Buildbucket for the build status
[02:22:10.709] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:22:10.709] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:12:0) after 1m0s
[02:23:10.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:12:0)
[02:23:10.704] Timer tick, asking Buildbucket for the build status
[02:23:10.804] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:23:10.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:13:0) after 1m0s
[02:24:10.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:13:0)
[02:24:10.833] Timer tick, asking Buildbucket for the build status
[02:24:10.981] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:24:10.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:14:0) after 1m0s
[02:25:11.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:14:0)
[02:25:11.000] Timer tick, asking Buildbucket for the build status
[02:25:11.093] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:25:11.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:15:0) after 1m0s
[02:26:11.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:15:0)
[02:26:11.151] Timer tick, asking Buildbucket for the build status
[02:26:11.212] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:26:11.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:16:0) after 1m0s
[02:27:11.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:16:0)
[02:27:11.228] Timer tick, asking Buildbucket for the build status
[02:27:11.291] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:27:11.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:17:0) after 1m0s
[02:28:11.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:17:0)
[02:28:11.319] Timer tick, asking Buildbucket for the build status
[02:28:11.434] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:28:11.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:18:0) after 1m0s
[02:29:11.465] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:18:0)
[02:29:11.465] Timer tick, asking Buildbucket for the build status
[02:29:11.539] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:29:11.539] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:19:0) after 1m0s
[02:30:11.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:19:0)
[02:30:11.580] Timer tick, asking Buildbucket for the build status
[02:30:11.680] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:30:11.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:20:0) after 1m0s
[02:31:11.712] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:20:0)
[02:31:11.712] Timer tick, asking Buildbucket for the build status
[02:31:11.908] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:31:11.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:21:0) after 1m0s
[02:32:11.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:21:0)
[02:32:11.909] Timer tick, asking Buildbucket for the build status
[02:32:12.137] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:32:12.137] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:22:0) after 1m0s
[02:33:12.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:22:0)
[02:33:12.141] Timer tick, asking Buildbucket for the build status
[02:33:12.242] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:33:12.242] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:23:0) after 1m0s
[02:34:12.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:23:0)
[02:34:12.287] Timer tick, asking Buildbucket for the build status
[02:34:12.436] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:34:12.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:24:0) after 1m0s
[02:35:12.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:24:0)
[02:35:12.449] Timer tick, asking Buildbucket for the build status
[02:35:12.570] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:35:12.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:25:0) after 1m0s
[02:36:12.615] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:25:0)
[02:36:12.615] Timer tick, asking Buildbucket for the build status
[02:36:14.466] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:36:14.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:26:0) after 1m0s
[02:37:14.495] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:26:0)
[02:37:14.495] Timer tick, asking Buildbucket for the build status
[02:37:15.422] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:37:15.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:27:0) after 1m0s
[02:38:15.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:27:0)
[02:38:15.407] Timer tick, asking Buildbucket for the build status
[02:38:15.592] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:38:15.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:28:0) after 1m0s
[02:39:15.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:28:0)
[02:39:15.621] Timer tick, asking Buildbucket for the build status
[02:39:15.718] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:39:15.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:29:0) after 1m0s
[02:40:15.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:29:0)
[02:40:15.743] Timer tick, asking Buildbucket for the build status
[02:40:16.021] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:40:16.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:30:0) after 1m0s
[02:41:16.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:30:0)
[02:41:16.045] Timer tick, asking Buildbucket for the build status
[02:41:16.176] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:41:16.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:31:0) after 1m0s
[02:42:16.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:31:0)
[02:42:16.154] Timer tick, asking Buildbucket for the build status
[02:42:16.496] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:42:16.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:32:0) after 1m0s
[02:43:16.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:32:0)
[02:43:16.517] Timer tick, asking Buildbucket for the build status
[02:43:16.731] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:43:16.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:33:0) after 1m0s
[02:44:16.760] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:33:0)
[02:44:16.760] Timer tick, asking Buildbucket for the build status
[02:44:17.191] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:44:17.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:34:0) after 1m0s
[02:45:17.211] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:34:0)
[02:45:17.211] Timer tick, asking Buildbucket for the build status
[02:45:17.390] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:45:17.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:35:0) after 1m0s
[02:46:17.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:35:0)
[02:46:17.419] Timer tick, asking Buildbucket for the build status
[02:46:17.883] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:46:17.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:36:0) after 1m0s
[02:47:17.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:36:0)
[02:47:17.895] Timer tick, asking Buildbucket for the build status
[02:47:18.349] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:47:18.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:37:0) after 1m0s
[02:48:18.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:37:0)
[02:48:18.357] Timer tick, asking Buildbucket for the build status
[02:48:18.465] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:48:18.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:38:0) after 1m0s
[02:49:18.478] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:38:0)
[02:49:18.478] Timer tick, asking Buildbucket for the build status
[02:49:18.961] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:49:18.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:39:0) after 1m0s
[02:50:18.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:39:0)
[02:50:18.983] Timer tick, asking Buildbucket for the build status
[02:50:19.073] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:50:19.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:40:0) after 1m0s
[02:51:19.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:40:0)
[02:51:19.148] Timer tick, asking Buildbucket for the build status
[02:51:19.307] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:51:19.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:41:0) after 1m0s
[02:52:19.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:41:0)
[02:52:19.302] Timer tick, asking Buildbucket for the build status
[02:52:19.416] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:52:19.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:42:0) after 1m0s
[02:53:19.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:42:0)
[02:53:19.424] Timer tick, asking Buildbucket for the build status
[02:53:19.543] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:53:19.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:43:0) after 1m0s
[02:54:19.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:43:0)
[02:54:19.566] Timer tick, asking Buildbucket for the build status
[02:54:19.721] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:54:19.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:44:0) after 1m0s
[02:55:19.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:44:0)
[02:55:19.713] Timer tick, asking Buildbucket for the build status
[02:55:19.874] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:55:19.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:45:0) after 1m0s
[02:56:19.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:45:0)
[02:56:19.883] Timer tick, asking Buildbucket for the build status
[02:56:20.026] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:56:20.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:46:0) after 1m0s
[02:57:20.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:46:0)
[02:57:20.074] Timer tick, asking Buildbucket for the build status
[02:57:20.645] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:57:20.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:47:0) after 1m0s
[02:58:20.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:47:0)
[02:58:20.674] Timer tick, asking Buildbucket for the build status
[02:58:21.217] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:58:21.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:48:0) after 1m0s
[02:59:21.255] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:48:0)
[02:59:21.255] Timer tick, asking Buildbucket for the build status
[02:59:21.514] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[02:59:21.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:49:0) after 1m0s
[03:00:21.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:49:0)
[03:00:21.943] Timer tick, asking Buildbucket for the build status
[03:00:22.025] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:00:22.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:50:0) after 1m0s
[03:01:22.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:50:0)
[03:01:22.127] Timer tick, asking Buildbucket for the build status
[03:01:22.328] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:22.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:51:0) after 1m0s
[03:02:22.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:51:0)
[03:02:22.377] Timer tick, asking Buildbucket for the build status
[03:02:22.545] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:22.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:52:0) after 1m0s
[03:03:22.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:52:0)
[03:03:22.558] Timer tick, asking Buildbucket for the build status
[03:03:22.702] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:22.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:53:0) after 1m0s
[03:04:22.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:53:0)
[03:04:22.751] Timer tick, asking Buildbucket for the build status
[03:04:22.876] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:22.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:54:0) after 1m0s
[03:05:22.991] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:54:0)
[03:05:22.991] Timer tick, asking Buildbucket for the build status
[03:05:23.171] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:23.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:55:0) after 1m0s
[03:06:23.383] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:55:0)
[03:06:23.383] Timer tick, asking Buildbucket for the build status
[03:06:23.720] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:23.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:56:0) after 1m0s
[03:07:23.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:56:0)
[03:07:23.838] Timer tick, asking Buildbucket for the build status
[03:07:24.004] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:24.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:57:0) after 1m0s
[03:08:24.102] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:57:0)
[03:08:24.102] Timer tick, asking Buildbucket for the build status
[03:08:24.241] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:24.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:58:0) after 1m0s
[03:09:24.655] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:58:0)
[03:09:24.655] Timer tick, asking Buildbucket for the build status
[03:09:24.751] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:24.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:59:0) after 1m0s
[03:10:24.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:59:0)
[03:10:24.807] Timer tick, asking Buildbucket for the build status
[03:10:24.931] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:24.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:60:0) after 1m0s
[03:11:24.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:60:0)
[03:11:24.993] Timer tick, asking Buildbucket for the build status
[03:11:25.069] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:25.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:61:0) after 1m0s
[03:12:25.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:61:0)
[03:12:25.240] Timer tick, asking Buildbucket for the build status
[03:12:25.313] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:25.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:62:0) after 1m0s
[03:13:25.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:62:0)
[03:13:25.358] Timer tick, asking Buildbucket for the build status
[03:13:25.460] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:25.460] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:63:0) after 1m0s
[03:14:25.623] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:63:0)
[03:14:25.623] Timer tick, asking Buildbucket for the build status
[03:14:25.687] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:25.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:64:0) after 1m0s
[03:15:25.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:64:0)
[03:15:25.801] Timer tick, asking Buildbucket for the build status
[03:15:26.321] Build 8860885198464799168: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:26.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9026315260479803776:65:0) after 1m0s
[03:15:57.267] Received PubSub notification, asking Buildbucket for the build status
[03:15:57.353] Build 8860885198464799168: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[03:15:57.353] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608002156557839",
"created_by": "project:chromium",
"created_ts": "1607998387807977",
"id": "8860885198464799168",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b9bb85d82a0449e5d55879a81f2424ef99346dc7\"}}",
"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\": \"b9bb85d82a0449e5d55879a81f2424ef99346dc7\", \"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-us-central1-b-32-1-qsbv\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16230, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"fd7733e974236d34b822c36becc965b8d3414f08\", \"got_dawn_revision\": \"43c39a1ba42a1b1bc94337b11fe744fc36663693\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"b9bb85d82a0449e5d55879a81f2424ef99346dc7\", \"got_revision_cp\": \"refs/heads/master@{#836922}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"fa44979d90aac8cca42e2fee711d44f23c9c4d67\", \"got_v8_revision\": \"a1714d05da29dc4e5e048f0dd11bb65fb08462d0\", \"got_v8_revision_cp\": \"refs/heads/8.9.147@{#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\": \"b9bb85d82a0449e5d55879a81f2424ef99346dc7\", \"swarm_hashes_refs/heads/master(at){#836922}_without_patch\": {\"absl_hardening_tests\": \"3270c52fc6cb6e231ee4dd4000d6f9fdc4536be7\", \"accessibility_unittests\": \"533f94bc6f7378a18a838cec696bd674b0f34574\", \"angle_unittests\": \"ea560dfbd30df8482339a5f78b4345a5e241ebd6\", \"app_shell_unittests\": \"4739e8acee1057f534d321ddcd0878972c39ad43\", \"aura_unittests\": \"edec86f8226bd889ab4be0d1b12f3dfc09110848\", \"base_unittests\": \"7b3e93dac2450b297bfaba1cb1e0a29ecbfd4568\", \"base_util_unittests\": \"527f7b2e7dbdaefd16bb260e5b75cf6faf82f4d6\", \"blink_common_unittests\": \"e2d7870222b8e4daf530d05c889f2a28621476d3\", \"blink_fuzzer_unittests\": \"d1426239909f0f9e099b28ef6436063e9cd2f32b\", \"blink_heap_unittests\": \"804af761591e6f9f4404591dd155dc4a0b22afff\", \"blink_platform_unittests\": \"4847d9ca3f21bbd73fcea57d5d88ecb2f8029255\", \"blink_unittests\": \"4446abb3cd6d7048f2836e298a5ba22123133299\", \"boringssl_crypto_tests\": \"04a39fef01623809fe128fbaca38f45a2b066003\", \"boringssl_ssl_tests\": \"cb7f7b99d642ae669c040b41e21f45ec04592220\", \"browser_switcher_bho_unittests\": \"9dd2627e3cc821fc7c38331744424776730fcaa4\", \"browser_tests\": \"dc81ebfd336aedb306b7e3ef5421fb26b84a14b6\", \"capture_unittests\": \"f8e1d93973bb35e384c369f63d96c12c3cf2f38d\", \"cast_unittests\": \"fd00e2a9e72c1ac2993492076b4cd345fd026d75\", \"cc_unittests\": \"4f21ee4eabd80d90863a158f0ceede30ea8ea2d9\", \"chrome_app_unittests\": \"2ee420e0c73a82ff406e9c5c91bd8ea7c8507f28\", \"chrome_cleaner_unittests\": \"ba890a1bb18c1605e099027eddef22741cd387a8\", \"chrome_elf_unittests\": \"7bd91908a4205c592f570c14b212d8ef11d1468a\", \"chromedriver_unittests\": \"d88f7ddb3ff36f1c8ee2ab3ab6decca1204fa992\", \"components_browsertests\": \"17fbd208ba3e2febae315e37e1d9fee13ad7b1ee\", \"components_unittests\": \"ddb5183d01eca18a3ae0b8f67ca21828b1b2d0c9\", \"compositor_unittests\": \"3395513e70f84df07d9b78d1cee305047d3e24d8\", \"content_browsertests\": \"cb54cfc9fc5bb4cc77b1647ea218d083642baf31\", \"content_unittests\": \"1992a864649705d31e39238a4d23e4c216e78f1d\", \"courgette_unittests\": \"38620d83c8e25492ea028c48c9f7089248cbc66c\", \"crashpad_tests\": \"5e47e2ddecbe2c86aaa89f35c19fd72b6f2bca62\", \"cronet_tests\": \"e97d983eed7b172a000798332f7105045d66f623\", \"cronet_unittests\": \"24f5697e3097947289bd4790347ceaa5a8a290e4\", \"crypto_unittests\": \"15b908e9459a595c3e52679963c07cfff7f26ac3\", \"delayloads_unittests\": \"3c833ebab90a2b9ec80084d760f9c4afe173cc67\", \"device_unittests\": \"6a2a37163a89f1dcf5f6b902c26a96853135b627\", \"display_unittests\": \"4d3734a0aca2df63839e54a6ac4ca10539a091e3\", \"elevation_service_unittests\": \"a720ceffb55ac0d8dda30981d7251cc72cef3f46\", \"events_unittests\": \"78923883738ae3da6f2f8ee2398db1ddaac87610\", \"extensions_browsertests\": \"0886392f0f9118ad1f5c2d66e7fbf3e2e40729b4\", \"extensions_unittests\": \"f31ecb524ba0743b01a4558f0126815d3896e4e7\", \"filesystem_service_unittests\": \"7afc144717704a5a66e78306f32de20c61dd245d\", \"gcm_unit_tests\": \"93758f3ff02aa8df530d47e9a3c7da3ae63affec\", \"gcp_unittests\": \"add45144f45f0118312afb508a26d40a616dfc1c\", \"gfx_unittests\": \"68fdb21809f0b4f4b84495f7c3b479acd588cca7\", \"gin_unittests\": \"0243bdc98f1b09789525a8787987c70173c52d59\", \"google_apis_unittests\": \"f3dfaf699b729d1cabcd88b8e0f8551ebddd8db3\", \"gpu_unittests\": \"c773582a48ee20f70fd36de6b00f47b7f732349e\", \"gwp_asan_unittests\": \"fef7527cd133a6ce83a0fa788ce2842bad0a2396\", \"headless_browsertests\": \"5a0f162fa1411df9dbf68dfe530d6a491b6e9e4a\", \"headless_unittests\": \"0373157de877d8c915255ac1b244004f0e3fdf90\", \"install_static_unittests\": \"7a177e83e3d10b1ecec4a116b02ad92705ddce90\", \"installer_util_unittests\": \"ed725116c40e7b9ec6cb90dd8fcee12b70ca120b\", \"interactive_ui_tests\": \"7ce00aa4a40f9bca7aa41bcb2e172bae6dd26290\", \"ipc_tests\": \"f757863e7288dae92b23447e194b4ab3bc0417ed\", \"jingle_unittests\": \"fed2df4aa6581e95c278353fe26fea8e53c46b5c\", \"latency_unittests\": \"7aae014bf33c92926005073ad25a415753d777d6\", \"libjingle_xmpp_unittests\": \"d949b2438f7db9836f39442b9f1075408579d735\", \"liburlpattern_unittests\": \"60c43cc1be07d632b63f4a766dba507682886e3e\", \"media_blink_unittests\": \"4b3ed80d2e8399529c2debbc2fbdf9ff39afc816\", \"media_unittests\": \"e17f128bcf94493c55d30ba8c140fbb65563f89a\", \"message_center_unittests\": \"46610c7d52da13b055fa944ae0bc2db308991ae8\", \"midi_unittests\": \"52843efc3848ca090af51e7611e59a4b90411b45\", \"mojo_core_unittests\": \"b9489b57cb82dcc68d975dc2688445dbd2def7aa\", \"mojo_unittests\": \"077f47482896050088c46a5bd5325d018a8923ed\", \"nacl_loader_unittests\": \"5fcd1a7fc7e41ed4d6ecc950998c470e50799907\", \"native_theme_unittests\": \"1554c165316dd10759c3ee4e2895406d71e82362\", \"net_unittests\": \"0bdbb8fb72ddcb3259106da7a53e62675e1a1f2d\", \"notification_helper_unittests\": \"83a357c5a75224e8b5ad8b39b08a2cd7ffab66c8\", \"pdf_unittests\": \"ea5d84afca17414be3b57bf7c8f6d6f7af90f751\", \"perfetto_unittests\": \"7561bcf875d97186360269487eaf4c1bbfb5ec12\", \"ppapi_unittests\": \"eb65b72cad1d995c9fb5de199db96aad32207f10\", \"printing_unittests\": \"cfe47661cc7ba977dccf6bd0e718a4113d83e546\", \"remoting_unittests\": \"4f1a82a06307396b60ff546c8458b854f7cf4a32\", \"sbox_integration_tests\": \"81cf740689ce3893a04f315dea04824907608818\", \"sbox_unittests\": \"eb0628034c680d67e84e5ef306fcbcf06baf6cc4\", \"sbox_validation_tests\": \"95f667646f813b5765819d2cfba72b6ae0d2d1d4\", \"service_manager_unittests\": \"3b58e61c1119df5f7184e7079da0c21314b7b6e1\", \"services_unittests\": \"02463e62a74efe76bc7e1e99ebb29e1568998977\", \"setup_unittests\": \"68379a9973830e538c43015d5122fe3c1e4cb8d0\", \"shell_dialogs_unittests\": \"fa4a52d3b0428d73e9addad6b3cddf3381db5d80\", \"skia_unittests\": \"9c273f7b35ea78ce3fe9345ed1fe6cd87fd16ee1\", \"snapshot_unittests\": \"1c45971675840de4bcd08caa4f08baee3b500f6d\", \"sql_unittests\": \"4d236a37d7ca56195fb52da3d23b309c501da859\", \"storage_unittests\": \"506814f0dfcd5b991410cbedfc182e7aa71955ab\", \"sync_integration_tests\": \"e9b78e7d8077c7b3081a92db6cf2861f80dd31e0\", \"traffic_annotation_auditor_unittests\": \"1e80e44f867a143ac346a853e2ec9a42bd04eb78\", \"ui_base_unittests\": \"d11ac0ad5c43772fdc3c4a9fb2b7d5d18c5e3856\", \"ui_touch_selection_unittests\": \"3ac570a6e2897f7f0ab715ac097693d40c66889f\", \"unit_tests\": \"eface9ac22079a2af1bc8854d2bf95274aab41eb\", \"updater_tests\": \"dfcd57b95b12ae66b29c7630be1c13e7b7b60a25\", \"url_unittests\": \"441c15e2e5f231f2e7d523f9da2ca7f90a196a40\", \"views_unittests\": \"41e28cd463347dd412211c7fcb1f2f24726a8669\", \"viz_unittests\": \"e44abf429926cecc62337fd4c7e84366927c3197\", \"vr_common_unittests\": \"381b169e9efcf1e559893fd6934b794e53f6db3b\", \"vr_pixeltests\": \"75326a36ce12a400f4fa5e55b02e3beab5aec3f0\", \"weblayer_browsertests\": \"a09af64da0d11a70ff65c8106db08ab93208cc35\", \"weblayer_unittests\": \"0ea7649919fb0788339e400ad63885fb644277a7\", \"wm_unittests\": \"9c8773860d1284091c172dced4d1c5d386cddb62\", \"wtf_unittests\": \"49ddd29f478f40725f20060bf9b40e0211e007e2\", \"zlib_unittests\": \"5bcf3e68572aceb4515a3c628760f92086aafcdf\", \"zucchini_unittests\": \"783ea13f0eca68350ab88b891d9ac1ec2814ba10\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_91c730c3e6ead475130e2ae78bc3f03e09cb9dcb91c3469d4450b29020110401_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-us-central1-b-32-1-qsbv\"], \"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-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1607998389929227",
"status": "COMPLETED",
"status_changed_ts": "1608002156889968",
"tags": [
"build_address:luci.chromium.ci/win-asan/16230",
"builder:win-asan",
"buildset:commit/git/b9bb85d82a0449e5d55879a81f2424ef99346dc7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b9bb85d82a0449e5d55879a81f2424ef99346dc7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9026315260479803776",
"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/8860885198464799168/+/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:507cfdc309cbb910",
"user_agent:luci-scheduler"
],
"updated_ts": "1608002156894358",
"url": "https://ci.chromium.org/b/8860885198464799168",
"utcnow_ts": "1608002157343778"
}
}
[03:15:57.353] Invocation finished in 1h2m50.851249227s with status SUCCEEDED