[23:09:14.403] New invocation is queued and will start shortly
[23:09:15.492] Starting the invocation (attempt 1)
[23:09:15.531] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:09:15.531] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:09:15.531] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022974740735314944",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d08c3302e805f0afac1a49aaf0bb04f372a808d3\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022974740735314944",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d08c3302e805f0afac1a49aaf0bb04f372a808d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d08c3302e805f0afac1a49aaf0bb04f372a808d3",
"gitiles_ref:refs/heads/master"
]
}
[23:09:16.107] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611184155624024",
"id": "8857544678790177136",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d08c3302e805f0afac1a49aaf0bb04f372a808d3\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611184155992568",
"tags": [
"build_address:luci.chromium.ci/win-asan/16919",
"builder:win-asan",
"buildset:commit/git/d08c3302e805f0afac1a49aaf0bb04f372a808d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d08c3302e805f0afac1a49aaf0bb04f372a808d3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022974740735314944",
"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/8857544678790177136/+/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": "1611184155992702",
"url": "https://ci.chromium.org/b/8857544678790177136",
"utcnow_ts": "1611184156099217"
}
}
[23:09:16.107] Task URL: https://ci.chromium.org/b/8857544678790177136
[23:09:16.107] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:2:0) after 1m0s
[23:09:18.044] Received PubSub notification, asking Buildbucket for the build status
[23:09:18.126] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:16.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:2:0)
[23:10:16.174] Timer tick, asking Buildbucket for the build status
[23:10:16.439] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:10:16.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:4:0) after 1m0s
[23:11:16.689] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:4:0)
[23:11:16.689] Timer tick, asking Buildbucket for the build status
[23:11:16.845] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:11:16.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:5:0) after 1m0s
[23:12:16.937] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:5:0)
[23:12:16.937] Timer tick, asking Buildbucket for the build status
[23:12:17.071] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:12:17.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:6:0) after 1m0s
[23:13:17.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:6:0)
[23:13:17.248] Timer tick, asking Buildbucket for the build status
[23:13:17.357] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:13:17.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:7:0) after 1m0s
[23:14:17.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:7:0)
[23:14:17.442] Timer tick, asking Buildbucket for the build status
[23:14:17.876] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:14:17.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:8:0) after 1m0s
[23:15:17.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:8:0)
[23:15:17.896] Timer tick, asking Buildbucket for the build status
[23:15:17.957] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:15:17.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:9:0) after 1m0s
[23:16:17.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:9:0)
[23:16:17.987] Timer tick, asking Buildbucket for the build status
[23:16:18.048] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:16:18.048] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:10:0) after 1m0s
[23:17:18.092] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:10:0)
[23:17:18.092] Timer tick, asking Buildbucket for the build status
[23:17:18.164] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:17:18.164] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:11:0) after 1m0s
[23:18:18.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:11:0)
[23:18:18.262] Timer tick, asking Buildbucket for the build status
[23:18:18.457] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:18:18.457] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:12:0) after 1m0s
[23:19:18.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:12:0)
[23:19:18.550] Timer tick, asking Buildbucket for the build status
[23:19:18.776] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:19:18.776] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:13:0) after 1m0s
[23:20:18.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:13:0)
[23:20:18.853] Timer tick, asking Buildbucket for the build status
[23:20:18.924] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:20:18.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:14:0) after 1m0s
[23:21:19.025] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:14:0)
[23:21:19.025] Timer tick, asking Buildbucket for the build status
[23:21:19.106] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:21:19.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:15:0) after 1m0s
[23:22:19.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:15:0)
[23:22:19.156] Timer tick, asking Buildbucket for the build status
[23:22:19.394] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:22:19.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:16:0) after 1m0s
[23:23:19.440] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:16:0)
[23:23:19.440] Timer tick, asking Buildbucket for the build status
[23:23:19.514] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:23:19.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:17:0) after 1m0s
[23:24:19.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:17:0)
[23:24:19.636] Timer tick, asking Buildbucket for the build status
[23:24:19.778] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:24:19.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:18:0) after 1m0s
[23:25:19.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:18:0)
[23:25:19.840] Timer tick, asking Buildbucket for the build status
[23:25:19.905] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:25:19.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:19:0) after 1m0s
[23:26:19.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:19:0)
[23:26:19.998] Timer tick, asking Buildbucket for the build status
[23:26:20.073] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:26:20.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:20:0) after 1m0s
[23:27:20.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:20:0)
[23:27:20.293] Timer tick, asking Buildbucket for the build status
[23:27:20.527] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:27:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:21:0) after 1m0s
[23:28:20.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:21:0)
[23:28:20.686] Timer tick, asking Buildbucket for the build status
[23:28:20.841] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:28:20.841] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:22:0) after 1m0s
[23:29:20.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:22:0)
[23:29:20.984] Timer tick, asking Buildbucket for the build status
[23:29:21.110] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:29:21.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:23:0) after 1m0s
[23:30:21.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:23:0)
[23:30:21.488] Timer tick, asking Buildbucket for the build status
[23:30:21.831] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:30:21.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:24:0) after 1m0s
[23:31:22.070] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:24:0)
[23:31:22.070] Timer tick, asking Buildbucket for the build status
[23:31:22.368] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:31:22.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:25:0) after 1m0s
[23:32:22.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:25:0)
[23:32:22.474] Timer tick, asking Buildbucket for the build status
[23:32:22.607] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:32:22.607] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:26:0) after 1m0s
[23:33:22.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:26:0)
[23:33:22.683] Timer tick, asking Buildbucket for the build status
[23:33:22.794] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:33:22.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:27:0) after 1m0s
[23:34:22.900] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:27:0)
[23:34:22.900] Timer tick, asking Buildbucket for the build status
[23:34:23.012] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:34:23.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:28:0) after 1m0s
[23:35:23.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:28:0)
[23:35:23.231] Timer tick, asking Buildbucket for the build status
[23:35:23.330] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:35:23.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:29:0) after 1m0s
[23:36:23.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:29:0)
[23:36:23.477] Timer tick, asking Buildbucket for the build status
[23:36:23.592] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:36:23.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:30:0) after 1m0s
[23:37:23.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:30:0)
[23:37:23.643] Timer tick, asking Buildbucket for the build status
[23:37:23.808] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:37:23.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:31:0) after 1m0s
[23:38:23.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:31:0)
[23:38:23.975] Timer tick, asking Buildbucket for the build status
[23:38:24.101] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:38:24.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:32:0) after 1m0s
[23:39:24.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:32:0)
[23:39:24.258] Timer tick, asking Buildbucket for the build status
[23:39:24.413] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:39:24.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:33:0) after 1m0s
[23:40:24.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:33:0)
[23:40:24.597] Timer tick, asking Buildbucket for the build status
[23:40:24.727] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:40:24.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:34:0) after 1m0s
[23:41:24.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:34:0)
[23:41:24.832] Timer tick, asking Buildbucket for the build status
[23:41:24.962] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:41:24.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:35:0) after 1m0s
[23:42:25.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:35:0)
[23:42:25.298] Timer tick, asking Buildbucket for the build status
[23:42:25.668] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:42:25.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:36:0) after 1m0s
[23:43:25.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:36:0)
[23:43:25.801] Timer tick, asking Buildbucket for the build status
[23:43:25.920] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:43:25.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:37:0) after 1m0s
[23:44:26.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:37:0)
[23:44:26.049] Timer tick, asking Buildbucket for the build status
[23:44:26.167] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:44:26.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:38:0) after 1m0s
[23:45:26.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:38:0)
[23:45:26.251] Timer tick, asking Buildbucket for the build status
[23:45:27.298] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:45:27.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:39:0) after 1m0s
[23:46:27.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:39:0)
[23:46:27.475] Timer tick, asking Buildbucket for the build status
[23:46:27.730] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:46:27.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:40:0) after 1m0s
[23:47:27.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:40:0)
[23:47:27.784] Timer tick, asking Buildbucket for the build status
[23:47:28.548] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:47:28.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:41:0) after 1m0s
[23:48:28.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:41:0)
[23:48:28.666] Timer tick, asking Buildbucket for the build status
[23:48:28.814] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:48:28.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:42:0) after 1m0s
[23:49:28.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:42:0)
[23:49:28.872] Timer tick, asking Buildbucket for the build status
[23:49:29.018] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:49:29.018] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:43:0) after 1m0s
[23:50:29.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:43:0)
[23:50:29.196] Timer tick, asking Buildbucket for the build status
[23:50:29.262] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:50:29.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:44:0) after 1m0s
[23:51:29.306] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:44:0)
[23:51:29.306] Timer tick, asking Buildbucket for the build status
[23:51:29.368] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:51:29.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:45:0) after 1m0s
[23:52:29.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:45:0)
[23:52:29.436] Timer tick, asking Buildbucket for the build status
[23:52:30.670] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:52:30.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:46:0) after 1m0s
[23:53:30.771] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:46:0)
[23:53:30.771] Timer tick, asking Buildbucket for the build status
[23:53:31.302] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:53:31.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:47:0) after 1m0s
[23:54:31.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:47:0)
[23:54:31.328] Timer tick, asking Buildbucket for the build status
[23:54:31.476] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:54:31.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:48:0) after 1m0s
[23:55:31.461] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:48:0)
[23:55:31.461] Timer tick, asking Buildbucket for the build status
[23:55:31.592] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:55:31.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:49:0) after 1m0s
[23:56:31.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:49:0)
[23:56:31.802] Timer tick, asking Buildbucket for the build status
[23:56:31.933] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:56:31.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:50:0) after 1m0s
[23:57:32.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:50:0)
[23:57:32.048] Timer tick, asking Buildbucket for the build status
[23:57:32.321] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:57:32.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:51:0) after 1m0s
[23:58:32.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:51:0)
[23:58:32.393] Timer tick, asking Buildbucket for the build status
[23:58:32.482] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:58:32.482] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:52:0) after 1m0s
[23:59:32.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:52:0)
[23:59:32.777] Timer tick, asking Buildbucket for the build status
[23:59:32.897] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[23:59:32.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:53:0) after 1m0s
[00:00:32.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:53:0)
[00:00:32.978] Timer tick, asking Buildbucket for the build status
[00:00:33.240] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:00:33.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:54:0) after 1m0s
[00:01:33.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:54:0)
[00:01:33.462] Timer tick, asking Buildbucket for the build status
[00:01:34.268] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:01:34.268] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:55:0) after 1m0s
[00:02:34.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:55:0)
[00:02:34.320] Timer tick, asking Buildbucket for the build status
[00:02:34.419] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:02:34.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:56:0) after 1m0s
[00:03:34.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:56:0)
[00:03:34.607] Timer tick, asking Buildbucket for the build status
[00:03:34.742] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:03:34.742] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:57:0) after 1m0s
[00:04:34.870] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:57:0)
[00:04:34.870] Timer tick, asking Buildbucket for the build status
[00:04:35.265] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:04:35.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:58:0) after 1m0s
[00:05:35.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:58:0)
[00:05:35.261] Timer tick, asking Buildbucket for the build status
[00:05:35.697] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:05:35.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:59:0) after 1m0s
[00:06:35.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:59:0)
[00:06:35.752] Timer tick, asking Buildbucket for the build status
[00:06:35.833] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:06:35.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:60:0) after 1m0s
[00:07:35.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:60:0)
[00:07:35.906] Timer tick, asking Buildbucket for the build status
[00:07:36.328] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:07:36.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:61:0) after 1m0s
[00:08:36.400] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:61:0)
[00:08:36.400] Timer tick, asking Buildbucket for the build status
[00:08:36.507] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:08:36.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:62:0) after 1m0s
[00:09:36.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:62:0)
[00:09:36.598] Timer tick, asking Buildbucket for the build status
[00:09:36.765] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:09:36.765] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:63:0) after 1m0s
[00:10:36.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:63:0)
[00:10:36.824] Timer tick, asking Buildbucket for the build status
[00:10:36.993] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:10:36.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:64:0) after 1m0s
[00:11:37.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:64:0)
[00:11:37.012] Timer tick, asking Buildbucket for the build status
[00:11:37.309] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:11:37.309] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:65:0) after 1m0s
[00:12:37.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:65:0)
[00:12:37.388] Timer tick, asking Buildbucket for the build status
[00:12:37.496] Build 8857544678790177136: status "STARTED", result "", failure_reason "", cancelation_reason ""
[00:12:37.496] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022974740735314944:66:0) after 1m0s
[00:12:39.655] Received PubSub notification, asking Buildbucket for the build status
[00:12:39.777] Build 8857544678790177136: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[00:12:39.777] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611187958309537",
"created_by": "project:chromium",
"created_ts": "1611184155624024",
"id": "8857544678790177136",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d08c3302e805f0afac1a49aaf0bb04f372a808d3\"}}",
"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\": \"d08c3302e805f0afac1a49aaf0bb04f372a808d3\", \"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-0-gbsn\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16919, \"clang_revision\": \"llvmorg-12-init-16548-gf1d5cbbd-1\", \"got_angle_revision\": \"ea71c6b63417af438afaf26a886b03621d85f14f\", \"got_dawn_revision\": \"064f33e4416ed498c91137f518fedb56c325b99b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"d08c3302e805f0afac1a49aaf0bb04f372a808d3\", \"got_revision_cp\": \"refs/heads/master@{#845387}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"e27e7488e4ad0ef2099149c66e8b57742e9d5a1c\", \"got_v8_revision\": \"1d681a1a9f5b66ce7c18d756f80e1c3afab911d5\", \"got_v8_revision_cp\": \"refs/heads/9.0.28@{#1}\", \"got_webrtc_revision\": \"812c73cdc25ade1c08750873f0c1e8f8def206dc\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33043}\", \"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\": \"d08c3302e805f0afac1a49aaf0bb04f372a808d3\", \"swarm_hashes_refs/heads/master(at){#845387}_without_patch\": {\"absl_hardening_tests\": \"3f014dd4de63d7e9a7ca29581ec8f37e7812391b\", \"accessibility_unittests\": \"2e92ffe9315ec9197e52e678549f31567d9ca549\", \"angle_unittests\": \"0aa149c8a1e3f3b1a0979d82523aebdbda6746e9\", \"app_shell_unittests\": \"8c87fa7a78606c5ba12e599a90b8694160ebed4e\", \"aura_unittests\": \"73b93735b8df8972ea25789058a70d5f3436ff74\", \"base_unittests\": \"6c9a837a40545654833eb51ff3d054014a06a4a7\", \"base_util_unittests\": \"5feac01c8bcee32e9be61b4d234f5c57ccfd5f14\", \"blink_common_unittests\": \"1b184d1195556152c789e1af428b73878f251ced\", \"blink_fuzzer_unittests\": \"6418a92fd8db38eda0a2c1c86720f895b1c539a1\", \"blink_heap_unittests\": \"c918436ce8b018c5a32893f09ed1ab9099b25b74\", \"blink_platform_unittests\": \"6842e6ce2e5b5aac9da0d7da1562d9cc2a2c00b1\", \"blink_unittests\": \"55154e0071af50b6eeba650babd8a7c1401e8a4e\", \"boringssl_crypto_tests\": \"5282675f6387eadd66b96136ef4a12cd746074b7\", \"boringssl_ssl_tests\": \"83d7251e3df241388c47fb434a026c33958d9cf4\", \"browser_switcher_bho_unittests\": \"4cb24c4ec8ecf478b004b6831b2284a28e95bc0b\", \"browser_tests\": \"6761fb06a788f74242163f6cd781302abacded88\", \"capture_unittests\": \"3c420e8230327e729ce55394d84eb0e8b00e9ed5\", \"cast_unittests\": \"0cb924168d728f0b5c0c3d7888cf99e03e0e3a17\", \"cc_unittests\": \"662b3d05949dee3cdbbcaa227f9a47aaceba7c25\", \"chrome_app_unittests\": \"fbfdcc2e9caf9670988b88667eefd2d5b5898420\", \"chrome_cleaner_unittests\": \"354dba4241f037a771f407db4f2d8d740a38f2d6\", \"chrome_elf_unittests\": \"2f8fb83191866dd1bd1397a84afd74e3a68daa96\", \"chromedriver_unittests\": \"ab5f785ee4ebe00afde4efead2b7251c0a3578ff\", \"components_browsertests\": \"0a834d7abdfb338a768b3c277e48d8b970780761\", \"components_unittests\": \"12fee0ed6ed2d1ba048ed38081e237f3b8b2f0f7\", \"compositor_unittests\": \"82d2b979e3db1f4a586fb5da1303f821b47f90d0\", \"content_browsertests\": \"ee87b257a0aa11a5b7ca4e0aa0f3abfddb754190\", \"content_unittests\": \"d49a5b80ce5b50db20ca064cd2854bb05b9c0adf\", \"courgette_unittests\": \"c5b00908647a4357e4222ca8171329e3f64f76b7\", \"crashpad_tests\": \"487bb6a15a562ca0e9a2b95ec465163812ec74b6\", \"cronet_tests\": \"2f6528ef9a467dba7c0b5dc1757139d4918007c8\", \"cronet_unittests\": \"adfe0935ce543caa11e5b691adf878c51e0d9285\", \"crypto_unittests\": \"e46087da67e759acd46f104161063dd549077bd0\", \"delayloads_unittests\": \"00129c8f7588ca44479400a6e7ddb7ace0880bac\", \"device_unittests\": \"f73feee1f805f163bdd37362bf6976ad71922019\", \"display_unittests\": \"66ac67e4ab9b2e8646b09ca45588dacbe31c0cc3\", \"elevation_service_unittests\": \"7b29f498ad0a807633adcc6757645fd1bd43f420\", \"events_unittests\": \"157b156da04c08a2893be27df7e2e410bead1803\", \"extensions_browsertests\": \"6f8143a6437336576133509156ced2a4bb3ff37c\", \"extensions_unittests\": \"08b07443ea47f5cba3aefb21c842d6a0bab908f5\", \"filesystem_service_unittests\": \"d6cbffe41e97ba59167acbde9316baeb3774a0c3\", \"gcm_unit_tests\": \"c29bc575da7a156380d5b5f2cbe74bfa5417a802\", \"gcp_unittests\": \"f57e73cc1efc63ad95c3e14a5fc93335e4b073ce\", \"gfx_unittests\": \"a1a8f6b6ffd9f7bbfb827d8377f1aaeef395d67b\", \"gin_unittests\": \"bb4ce78407c3cf004f2f294100a84f7685342cba\", \"google_apis_unittests\": \"fbe5424240e21153c614916c1eefba194f355b0f\", \"gpu_unittests\": \"9faf9b1dbfc958868c766ff5657a18c9a8dd0970\", \"gwp_asan_unittests\": \"fad6e72c1489819e4a502ca54f5b0e1441b362b6\", \"headless_browsertests\": \"4f59dd50280242b8cda1e36500d41ccd9b159795\", \"headless_unittests\": \"776eb45e0dfd2492639db01edfb0d708a6855080\", \"install_static_unittests\": \"4ead9c9be42be84a6464a752e092f6a9378bf739\", \"installer_util_unittests\": \"aaf3b2f853b60f44de2cc9187f6c7d73b334c25a\", \"interactive_ui_tests\": \"a32e17520784435cda4b2c87ef9b3153a386623a\", \"ipc_tests\": \"08f57c08735b6748327c1975db26ff5072c3f182\", \"jingle_unittests\": \"e19b99707b1073f2cd15dd332ef8e46bc4036b1b\", \"latency_unittests\": \"0ac4a3b2a61bfa95bfe8d5d9d0a5a47263a5b634\", \"libjingle_xmpp_unittests\": \"0c2209fc21499434cbb4b2dfda1335fe64d47cee\", \"liburlpattern_unittests\": \"03158c6a4b87c58307eab8d953c9a22c715bdd5c\", \"media_blink_unittests\": \"42192f77b2277367f62327feb2d9faefc163fbbb\", \"media_unittests\": \"5b68f542e07adc8905eeff75d83b88d033ff5a49\", \"message_center_unittests\": \"787e68d280103fc184a684a39ef4f0e6580c4ac4\", \"midi_unittests\": \"fa184b076c6f8a2eee199d0145580ee466eb7c3d\", \"mojo_core_unittests\": \"935cf046560a69ed21f97cce7068e624a2c1b421\", \"mojo_unittests\": \"fe68dbc9f0660ea36c12c16f8fb45ceebc9aa0c2\", \"nacl_loader_unittests\": \"7310045b3453bb1f31617a3a5203425d3fd77fdd\", \"native_theme_unittests\": \"22aa43df8340ab2989535a2809b49c6bdbb223c8\", \"net_unittests\": \"d78805b200e28553f310cf2dc5b9b8bd4d27ca4f\", \"notification_helper_unittests\": \"7631f9d8d5d445cb3c9e6d5833e5c76e47a9de7a\", \"pdf_unittests\": \"f739660475823d6c12ba3e7e1b5e22110a616d87\", \"perfetto_unittests\": \"2454099c418a7747e4f8c8034b9dbc8906b8de27\", \"ppapi_unittests\": \"15580905c715d0935fdbdc8ad6d28f97f6b23152\", \"printing_unittests\": \"4f1d32a276c654b1b9c4622b35beff555df90758\", \"remoting_unittests\": \"950583915fc3e58de98d8ed99088e07a5979c1a0\", \"sbox_integration_tests\": \"d45ba21e08c9e70f45feb06eeea931dc50d56dc7\", \"sbox_unittests\": \"273c9bcb03b3181898bbc9451f77569dd3dbbbea\", \"sbox_validation_tests\": \"786e95c51bf0519cd4462eb660c474918f18583a\", \"service_manager_unittests\": \"683962a128dcd3024f46bc9c25234267fb7ff532\", \"services_unittests\": \"66228a3a54b2b9f526b9d824f5f8d152d474ce3a\", \"setup_unittests\": \"366f29270d312220ecf21e45b207c810892ed7d3\", \"shell_dialogs_unittests\": \"fecb819a50531ff98049cb288fc39109ce65349a\", \"skia_unittests\": \"5e8ea9bd3509d1d306c5132e3a73845dc0cf0bc8\", \"snapshot_unittests\": \"dc53b908a256dc10d31e29e3ab712d0f0a8cf1f4\", \"sql_unittests\": \"465d2e510cbf2f7be3f5d9fd43dd47a0ca94b1ac\", \"storage_unittests\": \"d9459386dc60301f9aac1e8d0832f8d3d78a805c\", \"sync_integration_tests\": \"ce1d8ba71a38e3fcb7d0683f7b6e987fea48ca59\", \"traffic_annotation_auditor_unittests\": \"84043274b40d333ec5db0325077b29290fdf5ce3\", \"ui_base_unittests\": \"2b8bd01fd58e14b4dbd4d99bdf29726d69079f5f\", \"ui_touch_selection_unittests\": \"61085f36aaef13307c72b763cb6449fa827b47de\", \"unit_tests\": \"f21ecf25de79961062a15926e50926d0111b417c\", \"updater_tests\": \"e850d704b66c5f50ffdacd79317691e2c9d78f5e\", \"url_unittests\": \"b6ca0c25ee1539f9ed8d230599f9baf87cab6ee7\", \"views_unittests\": \"9fbfd2b0185d29433c6c6b298f2341b70bbe6c58\", \"viz_unittests\": \"d8835f84c415740a7260812c57fdac648366ccaa\", \"vr_common_unittests\": \"2fc187f703628b24f9ee3e9738290ec05a6802b0\", \"vr_pixeltests\": \"eb13eb996c906644c1cde160a6fcbeabf650ad69\", \"weblayer_browsertests\": \"1becbbfb68625ddb8d5c555f7c7e0949c5644011\", \"weblayer_unittests\": \"f50b765ed2441de7db719277f5ee78da11511437\", \"wm_unittests\": \"babc7dff49b7b902dbfa29a7e61c5612ae2c18f8\", \"wtf_unittests\": \"944246f43674b0595e2b9125377e86ec040aa64e\", \"zlib_unittests\": \"e8484b0ce7e0b127838496686d5f68d394d1c969\", \"zucchini_unittests\": \"a3ddfdddea2fcfe37db70f2fc5f57026d56255e2\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_91b2ef8341586e76bd64fa3093e74ce43aef344c2e2134fb3b046b47c3d4bcdb_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-0-gbsn\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5666-679c9bd\"], \"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": "1611184157316318",
"status": "COMPLETED",
"status_changed_ts": "1611187959273411",
"tags": [
"build_address:luci.chromium.ci/win-asan/16919",
"builder:win-asan",
"buildset:commit/git/d08c3302e805f0afac1a49aaf0bb04f372a808d3",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d08c3302e805f0afac1a49aaf0bb04f372a808d3",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022974740735314944",
"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/8857544678790177136/+/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:513ae0b64aedd110",
"user_agent:luci-scheduler"
],
"updated_ts": "1611187959277800",
"url": "https://ci.chromium.org/b/8857544678790177136",
"utcnow_ts": "1611187959767721"
}
}
[00:12:39.777] Invocation finished in 1h3m25.391107875s with status SUCCEEDED