[23:15:19.587] New invocation is queued and will start shortly
[23:15:20.799] Starting the invocation (attempt 1)
[23:15:20.832] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:15:20.832] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:15:20.832] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027866593996479872",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"64593db36fba6073c61b136212832ba90df2d08a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027866593996479872",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/64593db36fba6073c61b136212832ba90df2d08a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64593db36fba6073c61b136212832ba90df2d08a",
"gitiles_ref:refs/heads/master"
]
}
[23:15:21.659] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606518920946625",
"id": "8862436531908200464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64593db36fba6073c61b136212832ba90df2d08a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606518921558609",
"tags": [
"build_address:luci.chromium.ci/win-asan/15895",
"builder:win-asan",
"buildset:commit/git/64593db36fba6073c61b136212832ba90df2d08a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64593db36fba6073c61b136212832ba90df2d08a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027866593996479872",
"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/8862436531908200464/+/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": "1606518921558726",
"url": "https://ci.chromium.org/b/8862436531908200464",
"utcnow_ts": "1606518921652367"
}
}
[23:15:21.659] Task URL: https://ci.chromium.org/b/8862436531908200464
[23:15:21.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:2:0) after 1m0s
[23:15:26.773] Received PubSub notification, asking Buildbucket for the build status
[23:15:26.839] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:21.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:2:0)
[23:16:21.680] Timer tick, asking Buildbucket for the build status
[23:16:21.914] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:21.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:4:0) after 1m0s
[23:17:21.966] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:4:0)
[23:17:21.966] Timer tick, asking Buildbucket for the build status
[23:17:22.071] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:22.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:5:0) after 1m0s
[23:18:22.099] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:5:0)
[23:18:22.099] Timer tick, asking Buildbucket for the build status
[23:18:22.572] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:22.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:6:0) after 1m0s
[23:19:22.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:6:0)
[23:19:22.625] Timer tick, asking Buildbucket for the build status
[23:19:22.735] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:22.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:7:0) after 1m0s
[23:20:22.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:7:0)
[23:20:22.796] Timer tick, asking Buildbucket for the build status
[23:20:22.971] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:22.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:8:0) after 1m0s
[23:21:22.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:8:0)
[23:21:22.990] Timer tick, asking Buildbucket for the build status
[23:21:23.108] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:23.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:9:0) after 1m0s
[23:22:23.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:9:0)
[23:22:23.171] Timer tick, asking Buildbucket for the build status
[23:22:23.230] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:23.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:10:0) after 1m0s
[23:23:23.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:10:0)
[23:23:23.241] Timer tick, asking Buildbucket for the build status
[23:23:23.412] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:23.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:11:0) after 1m0s
[23:24:23.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:11:0)
[23:24:23.491] Timer tick, asking Buildbucket for the build status
[23:24:23.636] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:23.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:12:0) after 1m0s
[23:25:23.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:12:0)
[23:25:23.635] Timer tick, asking Buildbucket for the build status
[23:25:23.821] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:23.821] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:13:0) after 1m0s
[23:26:23.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:13:0)
[23:26:23.842] Timer tick, asking Buildbucket for the build status
[23:26:24.091] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:24.091] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:14:0) after 1m0s
[23:27:24.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:14:0)
[23:27:24.169] Timer tick, asking Buildbucket for the build status
[23:27:25.644] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:25.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:15:0) after 1m0s
[23:28:25.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:15:0)
[23:28:25.726] Timer tick, asking Buildbucket for the build status
[23:28:25.939] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:25.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:16:0) after 1m0s
[23:29:25.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:16:0)
[23:29:25.953] Timer tick, asking Buildbucket for the build status
[23:29:26.826] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:26.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:17:0) after 1m0s
[23:30:26.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:17:0)
[23:30:26.884] Timer tick, asking Buildbucket for the build status
[23:30:27.028] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:27.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:18:0) after 1m0s
[23:31:27.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:18:0)
[23:31:27.056] Timer tick, asking Buildbucket for the build status
[23:31:27.229] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:27.229] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:19:0) after 1m0s
[23:32:27.233] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:19:0)
[23:32:27.233] Timer tick, asking Buildbucket for the build status
[23:32:27.348] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:27.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:20:0) after 1m0s
[23:33:27.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:20:0)
[23:33:27.411] Timer tick, asking Buildbucket for the build status
[23:33:27.501] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:27.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:21:0) after 1m0s
[23:34:27.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:21:0)
[23:34:27.504] Timer tick, asking Buildbucket for the build status
[23:34:28.521] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:28.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:22:0) after 1m0s
[23:35:28.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:22:0)
[23:35:28.599] Timer tick, asking Buildbucket for the build status
[23:35:28.854] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:28.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:23:0) after 1m0s
[23:36:28.866] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:23:0)
[23:36:28.866] Timer tick, asking Buildbucket for the build status
[23:36:29.021] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:29.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:24:0) after 1m0s
[23:37:29.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:24:0)
[23:37:29.059] Timer tick, asking Buildbucket for the build status
[23:37:29.192] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:29.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:25:0) after 1m0s
[23:38:29.226] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:25:0)
[23:38:29.226] Timer tick, asking Buildbucket for the build status
[23:38:29.553] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:29.553] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:26:0) after 1m0s
[23:39:29.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:26:0)
[23:39:29.532] Timer tick, asking Buildbucket for the build status
[23:39:29.833] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:29.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:27:0) after 1m0s
[23:40:30.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:27:0)
[23:40:30.028] Timer tick, asking Buildbucket for the build status
[23:40:30.134] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:30.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:28:0) after 1m0s
[23:41:30.154] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:28:0)
[23:41:30.154] Timer tick, asking Buildbucket for the build status
[23:41:31.174] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:31.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:29:0) after 1m0s
[23:42:31.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:29:0)
[23:42:31.196] Timer tick, asking Buildbucket for the build status
[23:42:31.262] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:31.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:30:0) after 1m0s
[23:43:31.282] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:30:0)
[23:43:31.282] Timer tick, asking Buildbucket for the build status
[23:43:31.352] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:31.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:31:0) after 1m0s
[23:44:31.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:31:0)
[23:44:31.362] Timer tick, asking Buildbucket for the build status
[23:44:31.621] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:31.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:32:0) after 1m0s
[23:45:31.639] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:32:0)
[23:45:31.639] Timer tick, asking Buildbucket for the build status
[23:45:31.809] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:31.809] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:33:0) after 1m0s
[23:46:31.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:33:0)
[23:46:31.828] Timer tick, asking Buildbucket for the build status
[23:46:32.294] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:32.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:34:0) after 1m0s
[23:47:32.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:34:0)
[23:47:32.313] Timer tick, asking Buildbucket for the build status
[23:47:32.378] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:32.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:35:0) after 1m0s
[23:48:32.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:35:0)
[23:48:32.388] Timer tick, asking Buildbucket for the build status
[23:48:32.546] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:32.546] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:36:0) after 1m0s
[23:49:32.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:36:0)
[23:49:32.571] Timer tick, asking Buildbucket for the build status
[23:49:32.691] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:32.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:37:0) after 1m0s
[23:50:32.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:37:0)
[23:50:32.688] Timer tick, asking Buildbucket for the build status
[23:50:32.911] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:32.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:38:0) after 1m0s
[23:51:32.940] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:38:0)
[23:51:32.940] Timer tick, asking Buildbucket for the build status
[23:51:33.109] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:33.109] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:39:0) after 1m0s
[23:52:33.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:39:0)
[23:52:33.139] Timer tick, asking Buildbucket for the build status
[23:52:33.196] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:33.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:40:0) after 1m0s
[23:53:33.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:40:0)
[23:53:33.216] Timer tick, asking Buildbucket for the build status
[23:53:33.545] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:33.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:41:0) after 1m0s
[23:54:33.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:41:0)
[23:54:33.565] Timer tick, asking Buildbucket for the build status
[23:54:33.626] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:33.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:42:0) after 1m0s
[23:55:33.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:42:0)
[23:55:33.657] Timer tick, asking Buildbucket for the build status
[23:55:33.786] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:33.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:43:0) after 1m0s
[23:56:33.886] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:43:0)
[23:56:33.886] Timer tick, asking Buildbucket for the build status
[23:56:33.985] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:33.985] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:44:0) after 1m0s
[23:57:34.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:44:0)
[23:57:34.004] Timer tick, asking Buildbucket for the build status
[23:57:34.464] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:34.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:45:0) after 1m0s
[23:58:34.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:45:0)
[23:58:34.484] Timer tick, asking Buildbucket for the build status
[23:58:34.601] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:34.601] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:46:0) after 1m0s
[23:59:34.652] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:46:0)
[23:59:34.652] Timer tick, asking Buildbucket for the build status
[23:59:35.221] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:35.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:47:0) after 1m0s
[00:00:35.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:47:0)
[00:00:35.244] Timer tick, asking Buildbucket for the build status
[00:00:35.362] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:35.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:48:0) after 1m0s
[00:01:35.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:48:0)
[00:01:35.442] Timer tick, asking Buildbucket for the build status
[00:01:35.703] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:35.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:49:0) after 1m0s
[00:02:35.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:49:0)
[00:02:35.713] Timer tick, asking Buildbucket for the build status
[00:02:35.792] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:35.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:50:0) after 1m0s
[00:03:35.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:50:0)
[00:03:35.811] Timer tick, asking Buildbucket for the build status
[00:03:35.877] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:35.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:51:0) after 1m0s
[00:04:35.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:51:0)
[00:04:35.905] Timer tick, asking Buildbucket for the build status
[00:04:36.057] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:36.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:52:0) after 1m0s
[00:05:36.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:52:0)
[00:05:36.081] Timer tick, asking Buildbucket for the build status
[00:05:36.416] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:36.416] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:53:0) after 1m0s
[00:06:36.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:53:0)
[00:06:36.540] Timer tick, asking Buildbucket for the build status
[00:06:36.623] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:36.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:54:0) after 1m0s
[00:07:36.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:54:0)
[00:07:36.686] Timer tick, asking Buildbucket for the build status
[00:07:36.777] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:36.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:55:0) after 1m0s
[00:08:36.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:55:0)
[00:08:36.800] Timer tick, asking Buildbucket for the build status
[00:08:36.902] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.902] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:56:0) after 1m0s
[00:09:36.922] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:56:0)
[00:09:36.922] Timer tick, asking Buildbucket for the build status
[00:09:37.050] Build 8862436531908200464: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:37.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027866593996479872:57:0) after 1m0s
[00:09:39.492] Received PubSub notification, asking Buildbucket for the build status
[00:09:39.563] Build 8862436531908200464: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:09:39.563] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606522177996449",
"created_by": "project:chromium",
"created_ts": "1606518920946625",
"id": "8862436531908200464",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"64593db36fba6073c61b136212832ba90df2d08a\"}}",
"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\": \"64593db36fba6073c61b136212832ba90df2d08a\", \"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-4-qhsy\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15895, \"clang_revision\": \"llvmorg-12-init-12083-g94e4ec64-1\", \"got_angle_revision\": \"a5da17cd4e19649288b9634e7fff32455403b856\", \"got_dawn_revision\": \"e96986149080ca37e82788c907f439bc070aaa7b\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"64593db36fba6073c61b136212832ba90df2d08a\", \"got_revision_cp\": \"refs/heads/master@{#831654}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"9dff6a3bb5950edd890e5141f2d3d2f3b4ef351e\", \"got_v8_revision\": \"8d791670ba247239ef6ce9091183f11f8c5f0825\", \"got_v8_revision_cp\": \"refs/heads/8.9.65@{#1}\", \"got_webrtc_revision\": \"bcca3b08a90fccaa6dd8d86f6079f0ca0553ab4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32715}\", \"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\": \"64593db36fba6073c61b136212832ba90df2d08a\", \"swarm_hashes_refs/heads/master(at){#831654}_without_patch\": {\"absl_hardening_tests\": \"9509b936e1e93db1fce16ea3976e7b806fa31537\", \"accessibility_unittests\": \"b453f2b5562f20323ccbb21f64ca03ad7904e667\", \"angle_unittests\": \"192deb6ef56b86df372643139292597fc48ebc25\", \"app_shell_unittests\": \"24faff698c7bcc596041423a38cd9529e9536911\", \"aura_unittests\": \"917df3a43582c69c8fac57838fd9e27356eb4e44\", \"base_unittests\": \"5a01b95113ab79435dbfddcab08da0c597f9d643\", \"base_util_unittests\": \"e1edb8c892de0e4d36b3c3c2f659ff90d64b953e\", \"blink_common_unittests\": \"3f243666f31317d2144bae1c6183e9f3cded23e1\", \"blink_fuzzer_unittests\": \"cdb4f10164ba715c3940b1ae03fba3c4cd0c6b59\", \"blink_heap_unittests\": \"f3b43f515adff94fcfbceaac5cb55509165bbbe7\", \"blink_platform_unittests\": \"ef5e48bf55ae78360abad82b258ebe6a5c534bef\", \"blink_unittests\": \"dba9b849d4f872cb12323eb0fbb352a42a752b31\", \"boringssl_crypto_tests\": \"5ed56e4294391e789e6334bd8b4ef89d7801bb84\", \"boringssl_ssl_tests\": \"a27883cf305210b0c6c718c7620aa55d333fe9b6\", \"browser_switcher_bho_unittests\": \"aa682e26dbaf4ea03d16a9b39852f1951574bd4a\", \"browser_tests\": \"9a77e31671a40f6f70cd455056ecaafec6201339\", \"capture_unittests\": \"ff18fe8ec2b88e373b8e862921ea900dc4b42466\", \"cast_unittests\": \"3edafff0126c609a52be95d46b93f8ca98bb7540\", \"cc_unittests\": \"ba135175ed65c0fc44a126c3f4460570b08f2cf6\", \"chrome_app_unittests\": \"e8f3a92bf2db56b74fe4b6f3eafc9f4ce00cad64\", \"chrome_cleaner_unittests\": \"4038fdd7fdf5a796b45735e16ab0ffe5e1cba684\", \"chrome_elf_unittests\": \"70aad55c5e91add51c5125b96b01204352390d47\", \"chromedriver_unittests\": \"07e575fde1e73d7b25dbee7a52b807b8794cab6a\", \"components_browsertests\": \"9e2e8cd2faa1393d0e02197f75370ca07c717606\", \"components_unittests\": \"d8acfd453eb6d2fb180603417a727199fc853304\", \"compositor_unittests\": \"f75df333bb82232611eaa55269b9ea35c010fc4a\", \"content_browsertests\": \"ffc6939040f654021c595de513d7d8cbab5889f3\", \"content_unittests\": \"6357b9bb90478c9be16746a529d99e7df2536b65\", \"courgette_unittests\": \"da8dcd05044e7e94cf2f9c858ac5f00f0abd6e74\", \"crashpad_tests\": \"4231be2777e5527e07ac488ad2db6c4b31d13bb9\", \"cronet_tests\": \"94bd7b923903690737a258fef66184cd75fe37e0\", \"cronet_unittests\": \"7751056232f00b913c7a8c6540291d4b685d5ea2\", \"crypto_unittests\": \"9e3e9ea2685b65b9eb88cbd73754461955da9d30\", \"delayloads_unittests\": \"544b534cbe9f245a1e0f24a6aeacd11e846b0720\", \"device_unittests\": \"5332650fa98fefe685aa4dd2bce726b6499857ed\", \"display_unittests\": \"51799191667b3f70c8995748c3d6b5d276baf61e\", \"elevation_service_unittests\": \"070aff979cb962ebc0b6eefffbcb117737235d42\", \"events_unittests\": \"2bf55fa9dc4a09b49080ce05b1107a7e145c477f\", \"extensions_browsertests\": \"2662711740ce9ccb48c6e47a0df11a6dd8b14a48\", \"extensions_unittests\": \"a80eea0777bb3346eb995165a4d3fb9b515613df\", \"filesystem_service_unittests\": \"c74aabc5cf8b91ea1c7c50b05e3a3822db0fb0db\", \"gcm_unit_tests\": \"e2a8bdc8cb3c1a0425be5abff5a4138b025bb0cc\", \"gcp_unittests\": \"edb931f7ba2544e597cb56ad0f18bb9000e5a9a6\", \"gfx_unittests\": \"259b609ee8adc78ca5162f777c76882fa0e5312e\", \"gin_unittests\": \"49c893b177577058993259734c659e4e8e27065a\", \"google_apis_unittests\": \"ad9690cc9dd6801c3a779172a1ec8227155e7ce8\", \"gpu_unittests\": \"cedfbfcc3a7d58e978d7cf055c9366de70c33309\", \"gwp_asan_unittests\": \"8e1650ed78e7489fc10981a41cfb88d6d0553c0d\", \"headless_browsertests\": \"a52ea0d456c1fd9c10d61a2119d7f62b58b11f90\", \"headless_unittests\": \"26797e42f9b1e46bc626db713befd5fdf192adb7\", \"install_static_unittests\": \"2cea63a14149a580deea62da5e6497b168fe1c55\", \"installer_util_unittests\": \"ad0579a4cacf2eb75f2c0126ad4c1a3928c465c0\", \"interactive_ui_tests\": \"47eaded5423a38c0afadd63f0fcb883e843e899e\", \"ipc_tests\": \"e1c9c3303b094c4f50d67d7abed987a2fbccba1f\", \"jingle_unittests\": \"03c81238c77f4abeec7e2954f7c944333c97aedd\", \"latency_unittests\": \"7e504834a8562471a893fef37e57cb8827e0a032\", \"libjingle_xmpp_unittests\": \"e94ecbfca99448bdb459f44e8cd7d438a5898f71\", \"liburlpattern_unittests\": \"8becd94e286821bb962ffb78b9bd9710d57e7d26\", \"media_blink_unittests\": \"a280bc5b6d6c53d0ad19649cf84d61ab6fdb4b63\", \"media_unittests\": \"1ded741caead17dc4b143228af2ebd7d9db9c4a2\", \"message_center_unittests\": \"94ceb319732d06862f0f031493ec69b5af5eec0c\", \"midi_unittests\": \"236571f082a386b17596329707f3a612b61df1cc\", \"mojo_core_unittests\": \"eab4948b2421d413e680f349c9c1c29333dfae06\", \"mojo_unittests\": \"de3f923e55eb2703b769edac40733b31cd778089\", \"nacl_loader_unittests\": \"dee495f5c8fe17b7649e9b1c1fc6583b0e2c3833\", \"native_theme_unittests\": \"c15699a79ef0ba0b00a682f0680cc58b6817bcde\", \"net_unittests\": \"bf7972a8141477417ab490df8dd2931a7725096a\", \"notification_helper_unittests\": \"de9c6e085292387bf318df04b985878a625b6bb7\", \"pdf_unittests\": \"3f53b15c178a9f63f3f11add658cb4c2f5d24ef4\", \"perfetto_unittests\": \"70f78ad0db5bbbaf7d9f463b30801e25143462e8\", \"ppapi_unittests\": \"fa9c176fdef7aa2ea774aae4ee6fc614831b19d2\", \"printing_unittests\": \"df35c12e63b215fc695c82e4d46514706ab762c9\", \"remoting_unittests\": \"16bdb49ca4d25518a842f9b97654e59a9417b7fd\", \"sbox_integration_tests\": \"68c581528953aeda37be48e488fee96c586ecbed\", \"sbox_unittests\": \"22c289e956baad152bdc70ab7664ab33c693f45f\", \"sbox_validation_tests\": \"b416829327ac687f70ff60f4b1a1611db6cf232e\", \"service_manager_unittests\": \"30cac1a28644fdff5485f1e6f77f2c5c276e8dd1\", \"services_unittests\": \"e5e322988e90d83d1017c1b49a9f243539fa2cfb\", \"setup_unittests\": \"5db0fe135a20e8cbbfa58fb531347656f23d7228\", \"shell_dialogs_unittests\": \"ef6ed1b83c59d7ddde1ccec3e3cb70072594426c\", \"skia_unittests\": \"4bfc18378d4bd39252396546b10bcd686b72814e\", \"snapshot_unittests\": \"b52d98b70ebec27c060490d494086e0c2ebb2ca6\", \"sql_unittests\": \"7b0b80233e090efbfd19e40755d8efd562140154\", \"storage_unittests\": \"ca62929795ec1596add6db9035353a7ccbc4d8b8\", \"sync_integration_tests\": \"afeb34dcd22c8cbb2d751dc14df9b72d52b953c8\", \"traffic_annotation_auditor_unittests\": \"fa69f52c8f9031dd5259f2a39072e8cede12eb1d\", \"ui_base_unittests\": \"d81231ae7f445af41db1fec55131f5e6bd395dd3\", \"ui_touch_selection_unittests\": \"79e1060850f454babe3722931f51a8e0ca0fc071\", \"unit_tests\": \"e0da4d148dbe29b927240a713aae524b02dd16e9\", \"updater_tests\": \"38e1405226adb3690c25d452e12f7d3bc4feac03\", \"url_unittests\": \"4d2f28e5f33e832ba9417998319ca698f5fc03d9\", \"views_unittests\": \"41b7f79d421e4c28e308b03672573c8231c7608a\", \"viz_unittests\": \"f227d4a492323e6a9060010638e54c675e08a480\", \"vr_common_unittests\": \"0f7a2e7e59c1684562f222afc76479535f9472d6\", \"vr_pixeltests\": \"83c826932c9bd2d81fb4e7c8b80918c50d98782d\", \"weblayer_browsertests\": \"bb04cb191233b1c41b89c90f7218af397c5c19e5\", \"weblayer_unittests\": \"b0fd45c5171cb90483b43b3cca158424513004a1\", \"wm_unittests\": \"1fc7a8c96498d690142493801fa9312f169c9b62\", \"wtf_unittests\": \"f8e2a1ad1e18003c7c510b6dd147d139d2f2034d\", \"zlib_unittests\": \"074656959fac523a08e63074e3c144b21b39257d\", \"zucchini_unittests\": \"fd55c3644f4239fdc58c5ad73a1ee0f9fa7b528f\"}}, \"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-4-qhsy\"], \"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\": [\"5640-d0eb544\"], \"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": "1606518925538910",
"status": "COMPLETED",
"status_changed_ts": "1606522178477250",
"tags": [
"build_address:luci.chromium.ci/win-asan/15895",
"builder:win-asan",
"buildset:commit/git/64593db36fba6073c61b136212832ba90df2d08a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/64593db36fba6073c61b136212832ba90df2d08a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027866593996479872",
"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/8862436531908200464/+/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:5024cee221aa8110",
"user_agent:luci-scheduler"
],
"updated_ts": "1606522178484061",
"url": "https://ci.chromium.org/b/8862436531908200464",
"utcnow_ts": "1606522179555623"
}
}
[00:09:39.563] Invocation finished in 54m19.992392042s with status SUCCEEDED