[10:08:21.096] New invocation is queued and will start shortly
[10:08:22.485] Starting the invocation (attempt 1)
[10:08:22.519] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:08:22.519] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:08:22.519] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022027303357696640",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6aaff15abdbb5aa615642ce27770e8e326004490\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022027303357696640",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6aaff15abdbb5aa615642ce27770e8e326004490",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6aaff15abdbb5aa615642ce27770e8e326004490",
"gitiles_ref:refs/heads/master"
]
}
[10:08:23.683] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612087702690547",
"id": "8856597241022381552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6aaff15abdbb5aa615642ce27770e8e326004490\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612087703538664",
"tags": [
"build_address:luci.chromium.ci/win-asan/17090",
"builder:win-asan",
"buildset:commit/git/6aaff15abdbb5aa615642ce27770e8e326004490",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6aaff15abdbb5aa615642ce27770e8e326004490",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022027303357696640",
"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/8856597241022381552/+/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": "1612087703538805",
"url": "https://ci.chromium.org/b/8856597241022381552",
"utcnow_ts": "1612087703674836"
}
}
[10:08:23.683] Task URL: https://ci.chromium.org/b/8856597241022381552
[10:08:23.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:2:0) after 1m0s
[10:08:27.136] Received PubSub notification, asking Buildbucket for the build status
[10:08:27.412] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:23.895] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:2:0)
[10:09:23.895] Timer tick, asking Buildbucket for the build status
[10:09:24.006] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:24.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:4:0) after 1m0s
[10:10:24.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:4:0)
[10:10:24.195] Timer tick, asking Buildbucket for the build status
[10:10:24.289] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:24.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:5:0) after 1m0s
[10:11:24.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:5:0)
[10:11:24.503] Timer tick, asking Buildbucket for the build status
[10:11:24.597] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:24.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:6:0) after 1m0s
[10:12:24.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:6:0)
[10:12:24.668] Timer tick, asking Buildbucket for the build status
[10:12:24.840] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:24.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:7:0) after 1m0s
[10:13:24.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:7:0)
[10:13:24.869] Timer tick, asking Buildbucket for the build status
[10:13:24.968] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:24.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:8:0) after 1m0s
[10:14:24.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:8:0)
[10:14:24.988] Timer tick, asking Buildbucket for the build status
[10:14:25.070] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:25.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:9:0) after 1m0s
[10:15:25.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:9:0)
[10:15:25.147] Timer tick, asking Buildbucket for the build status
[10:15:25.236] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:25.236] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:10:0) after 1m0s
[10:16:25.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:10:0)
[10:16:25.232] Timer tick, asking Buildbucket for the build status
[10:16:25.296] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:25.296] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:11:0) after 1m0s
[10:17:25.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:11:0)
[10:17:25.293] Timer tick, asking Buildbucket for the build status
[10:17:25.383] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:25.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:12:0) after 1m0s
[10:18:25.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:12:0)
[10:18:25.438] Timer tick, asking Buildbucket for the build status
[10:18:25.557] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:25.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:13:0) after 1m0s
[10:19:25.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:13:0)
[10:19:25.567] Timer tick, asking Buildbucket for the build status
[10:19:25.626] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:14:0) after 1m0s
[10:20:25.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:14:0)
[10:20:25.723] Timer tick, asking Buildbucket for the build status
[10:20:25.802] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:25.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:15:0) after 1m0s
[10:21:25.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:15:0)
[10:21:25.956] Timer tick, asking Buildbucket for the build status
[10:21:26.317] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:26.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:16:0) after 1m0s
[10:22:26.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:16:0)
[10:22:26.444] Timer tick, asking Buildbucket for the build status
[10:22:26.931] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:26.931] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:17:0) after 1m0s
[10:23:26.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:17:0)
[10:23:26.974] Timer tick, asking Buildbucket for the build status
[10:23:27.101] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:27.101] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:18:0) after 1m0s
[10:24:27.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:18:0)
[10:24:27.212] Timer tick, asking Buildbucket for the build status
[10:24:27.414] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:27.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:19:0) after 1m0s
[10:25:27.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:19:0)
[10:25:27.483] Timer tick, asking Buildbucket for the build status
[10:25:28.427] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:28.427] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:20:0) after 1m0s
[10:26:28.456] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:20:0)
[10:26:28.456] Timer tick, asking Buildbucket for the build status
[10:26:29.053] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:29.053] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:21:0) after 1m0s
[10:27:29.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:21:0)
[10:27:29.183] Timer tick, asking Buildbucket for the build status
[10:27:29.292] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:29.292] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:22:0) after 1m0s
[10:28:29.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:22:0)
[10:28:29.452] Timer tick, asking Buildbucket for the build status
[10:28:29.567] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:29.567] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:23:0) after 1m0s
[10:29:29.604] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:23:0)
[10:29:29.604] Timer tick, asking Buildbucket for the build status
[10:29:29.730] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:29.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:24:0) after 1m0s
[10:30:29.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:24:0)
[10:30:29.752] Timer tick, asking Buildbucket for the build status
[10:30:29.876] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:29.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:25:0) after 1m0s
[10:31:29.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:25:0)
[10:31:29.951] Timer tick, asking Buildbucket for the build status
[10:31:30.064] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:30.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:26:0) after 1m0s
[10:32:30.086] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:26:0)
[10:32:30.086] Timer tick, asking Buildbucket for the build status
[10:32:30.417] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:30.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:27:0) after 1m0s
[10:33:30.472] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:27:0)
[10:33:30.472] Timer tick, asking Buildbucket for the build status
[10:33:30.568] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:30.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:28:0) after 1m0s
[10:34:30.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:28:0)
[10:34:30.579] Timer tick, asking Buildbucket for the build status
[10:34:30.652] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:30.652] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:29:0) after 1m0s
[10:35:30.625] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:29:0)
[10:35:30.625] Timer tick, asking Buildbucket for the build status
[10:35:30.739] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:30.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:30:0) after 1m0s
[10:36:30.759] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:30:0)
[10:36:30.759] Timer tick, asking Buildbucket for the build status
[10:36:30.869] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:30.869] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:31:0) after 1m0s
[10:37:30.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:31:0)
[10:37:30.959] Timer tick, asking Buildbucket for the build status
[10:37:31.025] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:31.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:32:0) after 1m0s
[10:38:31.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:32:0)
[10:38:31.088] Timer tick, asking Buildbucket for the build status
[10:38:31.418] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:31.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:33:0) after 1m0s
[10:39:31.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:33:0)
[10:39:31.429] Timer tick, asking Buildbucket for the build status
[10:39:31.507] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:31.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:34:0) after 1m0s
[10:40:31.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:34:0)
[10:40:31.531] Timer tick, asking Buildbucket for the build status
[10:40:31.637] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:31.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:35:0) after 1m0s
[10:41:31.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:35:0)
[10:41:31.669] Timer tick, asking Buildbucket for the build status
[10:41:31.911] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:31.911] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:36:0) after 1m0s
[10:42:31.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:36:0)
[10:42:31.953] Timer tick, asking Buildbucket for the build status
[10:42:32.026] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:32.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:37:0) after 1m0s
[10:43:32.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:37:0)
[10:43:32.170] Timer tick, asking Buildbucket for the build status
[10:43:32.527] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:32.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:38:0) after 1m0s
[10:44:32.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:38:0)
[10:44:32.558] Timer tick, asking Buildbucket for the build status
[10:44:33.033] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:33.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:39:0) after 1m0s
[10:45:33.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:39:0)
[10:45:33.164] Timer tick, asking Buildbucket for the build status
[10:45:33.337] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:33.338] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:40:0) after 1m0s
[10:46:33.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:40:0)
[10:46:33.389] Timer tick, asking Buildbucket for the build status
[10:46:33.859] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:33.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:41:0) after 1m0s
[10:47:33.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:41:0)
[10:47:33.983] Timer tick, asking Buildbucket for the build status
[10:47:34.117] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:34.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:42:0) after 1m0s
[10:48:34.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:42:0)
[10:48:34.304] Timer tick, asking Buildbucket for the build status
[10:48:34.668] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:34.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:43:0) after 1m0s
[10:49:34.798] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:43:0)
[10:49:34.798] Timer tick, asking Buildbucket for the build status
[10:49:35.059] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:35.059] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:44:0) after 1m0s
[10:50:35.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:44:0)
[10:50:35.111] Timer tick, asking Buildbucket for the build status
[10:50:35.169] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:35.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:45:0) after 1m0s
[10:51:35.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:45:0)
[10:51:35.274] Timer tick, asking Buildbucket for the build status
[10:51:35.367] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:35.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:46:0) after 1m0s
[10:52:35.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:46:0)
[10:52:35.378] Timer tick, asking Buildbucket for the build status
[10:52:35.480] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:35.480] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:47:0) after 1m0s
[10:53:35.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:47:0)
[10:53:35.599] Timer tick, asking Buildbucket for the build status
[10:53:35.942] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:35.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:48:0) after 1m0s
[10:54:36.135] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:48:0)
[10:54:36.135] Timer tick, asking Buildbucket for the build status
[10:54:36.612] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:36.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:49:0) after 1m0s
[10:55:36.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:49:0)
[10:55:36.693] Timer tick, asking Buildbucket for the build status
[10:55:36.764] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:36.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:50:0) after 1m0s
[10:56:36.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:50:0)
[10:56:36.854] Timer tick, asking Buildbucket for the build status
[10:56:36.950] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:36.950] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:51:0) after 1m0s
[10:57:37.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:51:0)
[10:57:37.054] Timer tick, asking Buildbucket for the build status
[10:57:37.128] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:37.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:52:0) after 1m0s
[10:58:37.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:52:0)
[10:58:37.190] Timer tick, asking Buildbucket for the build status
[10:58:37.302] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:37.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:53:0) after 1m0s
[10:59:37.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:53:0)
[10:59:37.333] Timer tick, asking Buildbucket for the build status
[10:59:38.082] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:38.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:54:0) after 1m0s
[11:00:38.185] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:54:0)
[11:00:38.185] Timer tick, asking Buildbucket for the build status
[11:00:38.274] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:38.274] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:55:0) after 1m0s
[11:01:38.298] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:55:0)
[11:01:38.298] Timer tick, asking Buildbucket for the build status
[11:01:38.718] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:38.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:56:0) after 1m0s
[11:02:38.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:56:0)
[11:02:38.831] Timer tick, asking Buildbucket for the build status
[11:02:39.008] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:39.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:57:0) after 1m0s
[11:03:39.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:57:0)
[11:03:39.108] Timer tick, asking Buildbucket for the build status
[11:03:39.262] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:39.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:58:0) after 1m0s
[11:04:39.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:58:0)
[11:04:39.243] Timer tick, asking Buildbucket for the build status
[11:04:39.333] Build 8856597241022381552: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:39.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022027303357696640:59:0) after 1m0s
[11:04:41.973] Received PubSub notification, asking Buildbucket for the build status
[11:04:42.054] Build 8856597241022381552: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:04:42.054] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612091079468038",
"created_by": "project:chromium",
"created_ts": "1612087702690547",
"id": "8856597241022381552",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6aaff15abdbb5aa615642ce27770e8e326004490\"}}",
"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\": \"6aaff15abdbb5aa615642ce27770e8e326004490\", \"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-y2bx\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17090, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"62387fc647193365e041fe4708a1492b4c787ed4\", \"got_dawn_revision\": \"c1d3a66bd217395a501543b09c6d2c25e9d2e22e\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"6aaff15abdbb5aa615642ce27770e8e326004490\", \"got_revision_cp\": \"refs/heads/master@{#848936}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8b4cf1c2491d56cf9eda153b5455e4b295732b7c\", \"got_v8_revision\": \"cbbe339c1ca9754b3123fe2bdb83c0f58ee5d728\", \"got_v8_revision_cp\": \"refs/heads/9.0.101@{#1}\", \"got_webrtc_revision\": \"5ac4212bec6faf20250d4632d0ced86c774b9e3e\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33114}\", \"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\": \"6aaff15abdbb5aa615642ce27770e8e326004490\", \"swarm_hashes_refs/heads/master(at){#848936}_without_patch\": {\"absl_hardening_tests\": \"54efe653eb29ffd0408a4310ba6170cd3f723d26\", \"accessibility_unittests\": \"14c367fc7d31cc45377d56bcc812fa3dae27e9aa\", \"angle_unittests\": \"1e0170a3ba742df71d6765bcc7df61aba7ab66fc\", \"app_shell_unittests\": \"1c9fe326ba9e2a57972c8c50bf84359d6f98e593\", \"aura_unittests\": \"6a41925921564bfaa049112293a1c5ad13d9d825\", \"base_unittests\": \"59ca6465fde0e52df071d0976cc81c47fa039154\", \"base_util_unittests\": \"b8a938daa05583c3356c2b6658659f4a4496dc76\", \"blink_common_unittests\": \"57ca3d0317b2eb204cf15eaf17fa080ab2a567b9\", \"blink_fuzzer_unittests\": \"d9cd14594f7b11a6f09b6c78b8898100e48f98e2\", \"blink_heap_unittests\": \"48e5354db99d48f7a7bf25f8a2645e062b1bb43c\", \"blink_platform_unittests\": \"0254b894a67334c3f2df20dc3806417ef7c906f1\", \"blink_unittests\": \"abc1e088793e3816934a6f38217607cd5fd7b66d\", \"boringssl_crypto_tests\": \"3c8d2559da392fae7f9aeef8f796d574e470b898\", \"boringssl_ssl_tests\": \"537a3ff703d15263942349feea13c0091e9cebd5\", \"browser_switcher_bho_unittests\": \"5aa750a9772e4e130f025c48361cb62cba0e5597\", \"browser_tests\": \"bccaad2bcc53818578cc4c9460d978d245a31c69\", \"capture_unittests\": \"e4d4011ebea5cf1addb7f633fa85fb9a2db2d3f6\", \"cast_unittests\": \"f7200dd3d416a0850fba845b68f033694dc3f912\", \"cc_unittests\": \"7f20ce3f7b33f66fdeb6ddd1f5a9991a44e8850c\", \"chrome_app_unittests\": \"30836d92838ec5fc0cd39daaefe92e1708fb6f66\", \"chrome_cleaner_unittests\": \"29bdde449f4c871405fc6d9bfdba1d490eb29e3a\", \"chrome_elf_unittests\": \"ca3be27ffda5f8ff9a33354f3c7bec5256695032\", \"chromedriver_unittests\": \"2d26fb733f0c69d622fd47bcc186641e518c3fdb\", \"components_browsertests\": \"7e9c5e2a42ff05ef515297ead08a1cafc14f2f2c\", \"components_unittests\": \"10e7797214c323033c0bb6a35a4be357162237dd\", \"compositor_unittests\": \"17aac19b2b1503a464bf2e96647eaed453765a68\", \"content_browsertests\": \"e894aca2867d4baee119dcd8b4ff1f774c824dfc\", \"content_unittests\": \"3a5df703709b0a07507051699147a6f30cdfb91d\", \"courgette_unittests\": \"01836ce5c6dd616175cd1913a7397fdb7b914055\", \"crashpad_tests\": \"1bc71d38c563b9953a09ced2dfb7195ee06cb3a2\", \"cronet_tests\": \"73f0fabeda4c9846ee859151c39ee2c344423fbd\", \"cronet_unittests\": \"46e3fcf02c4c5415e64dc3f12c7a987b416e03c7\", \"crypto_unittests\": \"9ba1caa01ef2664436cec9cc0327c9eaeae91602\", \"delayloads_unittests\": \"949849ea7905d04b0000eb7138c315141b162d83\", \"device_unittests\": \"b6493248cb504d73974a72ae07dcc6fa02bccd19\", \"display_unittests\": \"bb65b955085ef85a053a63057c4c94c4ce974cfa\", \"elevation_service_unittests\": \"976dea32f533b0c651090f56f6c3a670faa3578d\", \"events_unittests\": \"de43ccecce8816b389e0223dccde62113deca821\", \"extensions_browsertests\": \"42425795a6cea2309a7dd601bb6affcaeb3260b4\", \"extensions_unittests\": \"d76c3ce63cef52b0e85d388638690e1fe6cfb8c6\", \"filesystem_service_unittests\": \"0b7c53b96121ba682338d67bd8759716655adc23\", \"gcm_unit_tests\": \"ec0fc278bc87e541123fca00ababb9e6dfe9ee05\", \"gcp_unittests\": \"4e3fa85407cb25715b98ea9dad64a181fb58f16f\", \"gfx_unittests\": \"3f31812a0aefd91bf6a6f4b704c4a78890c08a7f\", \"gin_unittests\": \"c01087ea85cda169438957138e62a4ec5694e889\", \"google_apis_unittests\": \"a8fac1325125e0ef5b93fc78ce35da7397a565a2\", \"gpu_unittests\": \"89ec89f8a728c93564be12dd3edb8bfc75a057e7\", \"gwp_asan_unittests\": \"d922626f4d63943e2b30c1dee963555c20d07e61\", \"headless_browsertests\": \"df694c5f074519b7cbe6b283af55b09a21140a75\", \"headless_unittests\": \"878a6bd55b07cffe496b83c9bfe1693342c78013\", \"install_static_unittests\": \"c7cf8633cf329daefb3d7093c646612b8487ef0d\", \"installer_util_unittests\": \"00bb72bdbdecf8f07d636c4214f1ed0c244f5cbf\", \"interactive_ui_tests\": \"e8469040cd235ef4539e2aba30a1b388a9c86b95\", \"ipc_tests\": \"981e28a98a0c6f9b882f687ff7e4e9ba113a9173\", \"jingle_unittests\": \"f6b971b4a035bd0e7832e2554dae0d74bf176b80\", \"latency_unittests\": \"36265d0ae3265aafd560e5cae7dcfa69017922ab\", \"libjingle_xmpp_unittests\": \"1327d25f5573d93a4c711cfc01541512a5a724c5\", \"liburlpattern_unittests\": \"9d8a436360cafe877a842ac86e172549ed43b66e\", \"media_blink_unittests\": \"395287f619651db31c6f073e9fb70e71b6e29639\", \"media_unittests\": \"bb8eb7f12347846af3ce6fb0747891f1246c1ed2\", \"message_center_unittests\": \"f8ec80e693af11ca498cf171a00da2606fbf777e\", \"midi_unittests\": \"dc4bb666d6c61f615fd61dbc61d09eb0c2bcce7c\", \"mojo_core_unittests\": \"5e99d6e01d751cfa0fc244a36c25fcf7bf6954db\", \"mojo_unittests\": \"86adeb1d7e872cce8cecc3b940fc174699d073ce\", \"nacl_loader_unittests\": \"94ea7b89894e756ff6b4ebc19cf0a04e9330b920\", \"native_theme_unittests\": \"539e24c104ae3e4a0e35135677cb681c6639fcfa\", \"net_unittests\": \"a0e7352c4ce4612013d432b30e9253a554632145\", \"notification_helper_unittests\": \"83994e124df30abfff8b8c3984c2bf66fe2b2db3\", \"pdf_unittests\": \"79205faae0c9fc442f346543a8dd9bfd810cb7fa\", \"perfetto_unittests\": \"250b8a7a7bdb2c8185a5d9ec0f70562d44005242\", \"ppapi_unittests\": \"b823cc83a153cb8ac6d9a2d44a34abd42ad8e169\", \"printing_unittests\": \"8818c1c57c651b7a7b173c4fd71cce5b1b32d10c\", \"remoting_unittests\": \"28a03cc06b0e629423cdb6260e6edd749ba8ae5c\", \"sbox_integration_tests\": \"b8e746a2f9de2048eed46435e17f422ecd6e14d0\", \"sbox_unittests\": \"e9728144e8bd0110aedb71bf34553d0b3c45f525\", \"sbox_validation_tests\": \"80fbe13df3a07fe477a7450b65ddc4aa83b228d2\", \"service_manager_unittests\": \"9e9915aceee7b047b38e38104e10d50872ce3069\", \"services_unittests\": \"5157411d04136d698685634b8ed5807f43039739\", \"setup_unittests\": \"be97d42cb9f1ad83702996b4f3213e6ddbdf42f8\", \"shell_dialogs_unittests\": \"da6aba628d1021e3bc3bff87e5414d6e9979cbbf\", \"skia_unittests\": \"ec0e6beca9c0b473806c3a40542300134423aa19\", \"snapshot_unittests\": \"76e1271c6fe6d3a77ee3c040712f7a63f5e658be\", \"sql_unittests\": \"267040b731bfc2161f3dfa7b233b4d5d604cb3bf\", \"storage_unittests\": \"21daa1362c89c03237c465e6cb47f733bf496b8b\", \"sync_integration_tests\": \"97270fc5aa82c073072c8b865331b7e339eb045c\", \"traffic_annotation_auditor_unittests\": \"ec5d35c2e34616b972c0e51487143429415f061a\", \"ui_base_unittests\": \"86fc2ab06e22d9f30965224e334d27f792e9f0de\", \"ui_touch_selection_unittests\": \"e842e18980e1e802c1950229754e942e5f3426c8\", \"unit_tests\": \"e7efcd843373eecf0d7011c380af287f6cd3d31b\", \"updater_tests\": \"c08a5ba050b06c43cf3732d2ba9f182e07e3692d\", \"url_unittests\": \"07bb83330d8d20976982bf9e382019f2e8f41efe\", \"views_unittests\": \"40af02bf756d0fdba8fc70a2de4b86eb247706cc\", \"viz_unittests\": \"e96c0eed3b81abbba6777ec9c627f178f29a8643\", \"vr_common_unittests\": \"cae19d368c7554dc9a0e9c5b14470ff9aba5ca7b\", \"vr_pixeltests\": \"5b6b033927bd981fcf019107e16589084f838b48\", \"weblayer_browsertests\": \"59ab2655a2100ee840bfc6f5297e70b69adb6d47\", \"weblayer_unittests\": \"e1e70b3eddf85a33c279d0d8161a43e2f0c6b219\", \"wm_unittests\": \"da5ec45a679e8b03d464ff1400f7ebc5d27b86a9\", \"wtf_unittests\": \"7a596600aeb4c020c49534172401add3cd13f78e\", \"zlib_unittests\": \"e5553eff791df2decc8f7026c12654aaaa409792\", \"zucchini_unittests\": \"84d957c3537bb5c8a9b4834f7e2c8e032ca6792d\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-y2bx\"], \"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\": [\"5673-2413400\"], \"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": "1612087706332751",
"status": "COMPLETED",
"status_changed_ts": "1612091080124581",
"tags": [
"build_address:luci.chromium.ci/win-asan/17090",
"builder:win-asan",
"buildset:commit/git/6aaff15abdbb5aa615642ce27770e8e326004490",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6aaff15abdbb5aa615642ce27770e8e326004490",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022027303357696640",
"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/8856597241022381552/+/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:5170bbc123e7c510",
"user_agent:luci-scheduler"
],
"updated_ts": "1612091080197904",
"url": "https://ci.chromium.org/b/8856597241022381552",
"utcnow_ts": "1612091082046594"
}
}
[11:04:42.054] Invocation finished in 56m20.975369729s with status SUCCEEDED