[16:02:06.124] New invocation is queued and will start shortly
[16:02:07.236] Starting the invocation (attempt 1)
[16:02:07.276] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:02:07.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:02:07.276] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018381168649188448",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b3f9ed8e2189fb36fe069fa54e7d504210a38e39\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018381168649188448",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b3f9ed8e2189fb36fe069fa54e7d504210a38e39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3f9ed8e2189fb36fe069fa54e7d504210a38e39",
"gitiles_ref:refs/heads/master"
]
}
[16:02:08.009] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615564927416612",
"id": "8852951106627706432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b3f9ed8e2189fb36fe069fa54e7d504210a38e39\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615564927810026",
"tags": [
"build_address:luci.chromium.ci/win-asan/17773",
"builder:win-asan",
"buildset:commit/git/b3f9ed8e2189fb36fe069fa54e7d504210a38e39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3f9ed8e2189fb36fe069fa54e7d504210a38e39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018381168649188448",
"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/8852951106627706432/+/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": "1615564927810139",
"url": "https://ci.chromium.org/b/8852951106627706432",
"utcnow_ts": "1615564927998447"
}
}
[16:02:08.009] Task URL: https://ci.chromium.org/b/8852951106627706432
[16:02:08.009] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:2:0) after 1m0s
[16:02:22.618] Received PubSub notification, asking Buildbucket for the build status
[16:02:22.812] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:08.164] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:2:0)
[16:03:08.164] Timer tick, asking Buildbucket for the build status
[16:03:08.345] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:08.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:4:0) after 1m0s
[16:04:08.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:4:0)
[16:04:08.444] Timer tick, asking Buildbucket for the build status
[16:04:08.523] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:08.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:5:0) after 1m0s
[16:05:08.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:5:0)
[16:05:08.560] Timer tick, asking Buildbucket for the build status
[16:05:08.639] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:08.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:6:0) after 1m0s
[16:06:08.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:6:0)
[16:06:08.750] Timer tick, asking Buildbucket for the build status
[16:06:08.919] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:08.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:7:0) after 1m0s
[16:07:09.023] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:7:0)
[16:07:09.023] Timer tick, asking Buildbucket for the build status
[16:07:09.160] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:09.160] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:8:0) after 1m0s
[16:08:09.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:8:0)
[16:08:09.179] Timer tick, asking Buildbucket for the build status
[16:08:09.262] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:09.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:9:0) after 1m0s
[16:09:09.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:9:0)
[16:09:09.299] Timer tick, asking Buildbucket for the build status
[16:09:09.455] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:09.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:10:0) after 1m0s
[16:10:09.526] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:10:0)
[16:10:09.526] Timer tick, asking Buildbucket for the build status
[16:10:09.758] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:09.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:11:0) after 1m0s
[16:11:09.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:11:0)
[16:11:09.779] Timer tick, asking Buildbucket for the build status
[16:11:09.850] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:09.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:12:0) after 1m0s
[16:12:09.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:12:0)
[16:12:09.896] Timer tick, asking Buildbucket for the build status
[16:12:10.074] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:10.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:13:0) after 1m0s
[16:13:10.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:13:0)
[16:13:10.146] Timer tick, asking Buildbucket for the build status
[16:13:10.647] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:10.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:14:0) after 1m0s
[16:14:10.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:14:0)
[16:14:10.946] Timer tick, asking Buildbucket for the build status
[16:14:11.098] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:11.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:15:0) after 1m0s
[16:15:11.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:15:0)
[16:15:11.193] Timer tick, asking Buildbucket for the build status
[16:15:12.548] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:12.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:16:0) after 1m0s
[16:16:12.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:16:0)
[16:16:12.564] Timer tick, asking Buildbucket for the build status
[16:16:12.832] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:12.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:17:0) after 1m0s
[16:17:12.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:17:0)
[16:17:12.946] Timer tick, asking Buildbucket for the build status
[16:17:13.365] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:13.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:18:0) after 1m0s
[16:18:13.391] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:18:0)
[16:18:13.391] Timer tick, asking Buildbucket for the build status
[16:18:13.466] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:13.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:19:0) after 1m0s
[16:19:13.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:19:0)
[16:19:13.561] Timer tick, asking Buildbucket for the build status
[16:19:13.642] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:13.642] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:20:0) after 1m0s
[16:20:13.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:20:0)
[16:20:13.684] Timer tick, asking Buildbucket for the build status
[16:20:13.811] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:13.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:21:0) after 1m0s
[16:21:13.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:21:0)
[16:21:13.851] Timer tick, asking Buildbucket for the build status
[16:21:13.928] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:13.928] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:22:0) after 1m0s
[16:22:13.969] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:22:0)
[16:22:13.969] Timer tick, asking Buildbucket for the build status
[16:22:14.073] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:14.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:23:0) after 1m0s
[16:23:14.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:23:0)
[16:23:14.134] Timer tick, asking Buildbucket for the build status
[16:23:14.203] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:14.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:24:0) after 1m0s
[16:24:14.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:24:0)
[16:24:14.269] Timer tick, asking Buildbucket for the build status
[16:24:14.345] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:14.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:25:0) after 1m0s
[16:25:14.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:25:0)
[16:25:14.388] Timer tick, asking Buildbucket for the build status
[16:25:14.467] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:14.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:26:0) after 1m0s
[16:26:14.500] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:26:0)
[16:26:14.500] Timer tick, asking Buildbucket for the build status
[16:26:14.813] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:14.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:27:0) after 1m0s
[16:27:14.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:27:0)
[16:27:14.897] Timer tick, asking Buildbucket for the build status
[16:27:15.054] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:15.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:28:0) after 1m0s
[16:28:15.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:28:0)
[16:28:15.261] Timer tick, asking Buildbucket for the build status
[16:28:15.335] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:15.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:29:0) after 1m0s
[16:29:15.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:29:0)
[16:29:15.464] Timer tick, asking Buildbucket for the build status
[16:29:15.571] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:15.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:30:0) after 1m0s
[16:30:15.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:30:0)
[16:30:15.680] Timer tick, asking Buildbucket for the build status
[16:30:15.756] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:15.756] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:31:0) after 1m0s
[16:31:16.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:31:0)
[16:31:16.109] Timer tick, asking Buildbucket for the build status
[16:31:16.192] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:16.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:32:0) after 1m0s
[16:32:16.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:32:0)
[16:32:16.429] Timer tick, asking Buildbucket for the build status
[16:32:16.755] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:16.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:33:0) after 1m0s
[16:33:16.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:33:0)
[16:33:16.757] Timer tick, asking Buildbucket for the build status
[16:33:16.989] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:16.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:34:0) after 1m0s
[16:34:17.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:34:0)
[16:34:17.127] Timer tick, asking Buildbucket for the build status
[16:34:17.207] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:17.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:35:0) after 1m0s
[16:35:17.290] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:35:0)
[16:35:17.290] Timer tick, asking Buildbucket for the build status
[16:35:17.379] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:17.379] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:36:0) after 1m0s
[16:36:17.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:36:0)
[16:36:17.457] Timer tick, asking Buildbucket for the build status
[16:36:17.535] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:17.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:37:0) after 1m0s
[16:37:17.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:37:0)
[16:37:17.621] Timer tick, asking Buildbucket for the build status
[16:37:17.769] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:17.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:38:0) after 1m0s
[16:38:17.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:38:0)
[16:38:17.836] Timer tick, asking Buildbucket for the build status
[16:38:17.913] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:17.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:39:0) after 1m0s
[16:39:18.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:39:0)
[16:39:18.008] Timer tick, asking Buildbucket for the build status
[16:39:18.500] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:18.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:40:0) after 1m0s
[16:40:18.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:40:0)
[16:40:18.816] Timer tick, asking Buildbucket for the build status
[16:40:18.891] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:18.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:41:0) after 1m0s
[16:41:19.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:41:0)
[16:41:19.001] Timer tick, asking Buildbucket for the build status
[16:41:19.110] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:19.110] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:42:0) after 1m0s
[16:42:19.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:42:0)
[16:42:19.359] Timer tick, asking Buildbucket for the build status
[16:42:19.442] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:19.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:43:0) after 1m0s
[16:43:19.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:43:0)
[16:43:19.693] Timer tick, asking Buildbucket for the build status
[16:43:20.100] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:20.100] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:44:0) after 1m0s
[16:44:20.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:44:0)
[16:44:20.143] Timer tick, asking Buildbucket for the build status
[16:44:20.224] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:20.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:45:0) after 1m0s
[16:45:20.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:45:0)
[16:45:20.272] Timer tick, asking Buildbucket for the build status
[16:45:20.359] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:20.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:46:0) after 1m0s
[16:46:20.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:46:0)
[16:46:20.350] Timer tick, asking Buildbucket for the build status
[16:46:20.447] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:20.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:47:0) after 1m0s
[16:47:20.498] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:47:0)
[16:47:20.498] Timer tick, asking Buildbucket for the build status
[16:47:20.588] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:20.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:48:0) after 1m0s
[16:48:20.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:48:0)
[16:48:20.641] Timer tick, asking Buildbucket for the build status
[16:48:20.719] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:20.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:49:0) after 1m0s
[16:49:20.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:49:0)
[16:49:20.938] Timer tick, asking Buildbucket for the build status
[16:49:21.058] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:21.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:50:0) after 1m0s
[16:50:21.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:50:0)
[16:50:21.064] Timer tick, asking Buildbucket for the build status
[16:50:21.370] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:21.370] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:51:0) after 1m0s
[16:51:21.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:51:0)
[16:51:21.418] Timer tick, asking Buildbucket for the build status
[16:51:21.563] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:21.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:52:0) after 1m0s
[16:52:21.635] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:52:0)
[16:52:21.635] Timer tick, asking Buildbucket for the build status
[16:52:21.747] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:21.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:53:0) after 1m0s
[16:53:21.799] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:53:0)
[16:53:21.799] Timer tick, asking Buildbucket for the build status
[16:53:21.874] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:21.874] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:54:0) after 1m0s
[16:54:21.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:54:0)
[16:54:21.938] Timer tick, asking Buildbucket for the build status
[16:54:22.043] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:22.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:55:0) after 1m0s
[16:55:22.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:55:0)
[16:55:22.028] Timer tick, asking Buildbucket for the build status
[16:55:22.106] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:22.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:56:0) after 1m0s
[16:56:22.129] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:56:0)
[16:56:22.129] Timer tick, asking Buildbucket for the build status
[16:56:22.275] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:22.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:57:0) after 1m0s
[16:57:22.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:57:0)
[16:57:22.356] Timer tick, asking Buildbucket for the build status
[16:57:22.579] Build 8852951106627706432: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:22.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018381168649188448:58:0) after 1m0s
[16:57:44.998] Received PubSub notification, asking Buildbucket for the build status
[16:57:45.133] Build 8852951106627706432: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:57:45.133] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615568264277154",
"created_by": "project:chromium",
"created_ts": "1615564927416612",
"id": "8852951106627706432",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b3f9ed8e2189fb36fe069fa54e7d504210a38e39\"}}",
"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\": \"b3f9ed8e2189fb36fe069fa54e7d504210a38e39\", \"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-2-zecp\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17773, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-2\", \"got_angle_revision\": \"b775012c55a3894b35bbcc4f21838752ac6c7764\", \"got_dawn_revision\": \"a57308e60b7879ab89bb59abe90b01b5f21e92b5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"b3f9ed8e2189fb36fe069fa54e7d504210a38e39\", \"got_revision_cp\": \"refs/heads/master@{#862420}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"3c4fd15fb075ab3a8eb5809f9a42b5faa9ac63ae\", \"got_v8_revision\": \"9d12b666c3e113f5cd61e2b2eb384aae242ac840\", \"got_v8_revision_cp\": \"refs/heads/9.1.105@{#1}\", \"got_webrtc_revision\": \"14a626af56675c9d95f9fb207d53366a53a95fbd\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33409}\", \"is_cached\": true, \"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\": \"b3f9ed8e2189fb36fe069fa54e7d504210a38e39\", \"swarm_hashes_refs/heads/master(at){#862420}_without_patch\": {\"absl_hardening_tests\": \"6957fbadd83f22031a9a6620d66a8bf890e1458c\", \"accessibility_unittests\": \"fc310b205692bff450de880d9e281b4290c923e6\", \"angle_unittests\": \"f36ae7dc576159522567d28825e80d048f54743d\", \"app_shell_unittests\": \"fab804926b981238f394418d7ea333acfb527fff\", \"aura_unittests\": \"5fcceffffbb40dec60667a7a52c0c656c2e8b087\", \"base_unittests\": \"5d8eacafbcac581bb88a71bcca890a9f42395bf2\", \"base_util_unittests\": \"d77051f568ed90e512896e07c3a78aab150ce932\", \"blink_common_unittests\": \"9c2304f1f908ac25dc885492358f8b81c6b016fe\", \"blink_fuzzer_unittests\": \"4053ee0d95ee20d3175a47c8c319855e385d6752\", \"blink_heap_unittests\": \"c766ebc03556465c27da5e54f51bb8d98002167e\", \"blink_platform_unittests\": \"c9a3aa414b2b3c5a945edc409a3d10e2c9a48d06\", \"blink_unittests\": \"77657de5a2984e7acc601cc96ab4e700a7b32ced\", \"boringssl_crypto_tests\": \"150d0ffcc222abbe69d00741bfc307fb834697ad\", \"boringssl_ssl_tests\": \"fca5a0f6ab6457d11544c1d64c21738f93ee51c5\", \"browser_switcher_bho_unittests\": \"778e9302ad0bde2b7effceccadab24a0c07cb91f\", \"browser_tests\": \"70851c58bac82a072fb3be198c5665198565e815\", \"capture_unittests\": \"bb6aeb13ca5f8f355f00aabba395a7d39fa8b1cc\", \"cast_unittests\": \"acc450b71139cf27745ce1d31192f9c824474b8d\", \"cc_unittests\": \"faec229729ddc98504ac917be45b9e16de30fca1\", \"chrome_app_unittests\": \"fff9f6d5653d0749815d50947616473d1acecbe4\", \"chrome_cleaner_unittests\": \"1d10078b7e049db682257363161cbc1107616baf\", \"chrome_elf_unittests\": \"89af0304c2216da01639e3664b48fa5122dfff85\", \"chromedriver_unittests\": \"1097b4e1e568958faf6352949c8961e8798ba634\", \"color_unittests\": \"adea53f509dfae59fea10e0d824ca1f0edff2411\", \"components_browsertests\": \"4ba742e158d99374265318c2b257c35f6f943c38\", \"components_unittests\": \"de1dda86e7b3e801a8f45ad25770fa00ad73aee9\", \"compositor_unittests\": \"92e072fc9d66d8b0fc9b2c0cef533f6e75db993c\", \"content_browsertests\": \"2b5ca4eba673ab22e4f65ec207bc4aeaec31f3f7\", \"content_unittests\": \"1c2bd4807da811e7bd967db0bb3370ad1770fce5\", \"courgette_unittests\": \"fa8acb99cfd37554eed09e2753bb0570d527daab\", \"crashpad_tests\": \"bc64bba1bbe8e04250a5c0c99ca59aa4c42e0591\", \"cronet_tests\": \"edcd4a3e94e2075c6fbf4333cb4e703799821f2d\", \"cronet_unittests\": \"b60d60b03e655e0104187352e4a9692114bde59a\", \"crypto_unittests\": \"fdcdac5da2ea5dba65da81ce39a4618a0644b8d8\", \"delayloads_unittests\": \"84a902b15a8cea0116788cabfe37057a7db7dfc8\", \"device_unittests\": \"56de3a684bd104f915e1de11607e3041a633b2fb\", \"display_unittests\": \"80e3bada73522d5d89d16bbaa0b49c98a0c8678a\", \"elevation_service_unittests\": \"e5be0038c6b3dca48d4d0182ef266594f8c26e14\", \"events_unittests\": \"33e623cb1b110b473faf0e6fe4ace855db8eb4d0\", \"extensions_browsertests\": \"3608c6625b1eeee439f991433038e19ef556c48f\", \"extensions_unittests\": \"7d0fc86e15cfa3df6ec4a0f6509f8a38cdf62145\", \"filesystem_service_unittests\": \"97ba544dffc16991703d299eede26094b6312baa\", \"gcm_unit_tests\": \"f120af51c9c4f3343737a0dd83b06aeaeb082ab5\", \"gcp_unittests\": \"771ac952a9cb8a26aae3608d954168e9bec1c31d\", \"gfx_unittests\": \"e61885bfcd6d71391c57e5dd59e1b636cf78a691\", \"gin_unittests\": \"1132c246b25152ba559be9a35fc72b7e11766f5c\", \"google_apis_unittests\": \"d944484689507e89a9a4fc51b5362cb8c7bc657e\", \"gpu_unittests\": \"8281bf2c216bca81aca8b00e548cd259288ee48d\", \"gwp_asan_unittests\": \"ca8bd7941075b7970ec9253591a97649201ce0b2\", \"headless_browsertests\": \"23bd1b61f839f082da7396426d40cf29a8b13691\", \"headless_unittests\": \"16fcf56a26bd93361e6dfebb0f0d8efe97c15909\", \"install_static_unittests\": \"92207f78d86db7750b47972981bf2cae1d72c6b5\", \"installer_util_unittests\": \"94638a7238a35e8465897749b809ddc574871aa3\", \"interactive_ui_tests\": \"e1961651e0552b5461600977cbb5e6fccdbfc771\", \"ipc_tests\": \"fa0d028fda8d839e78a6fdb5fc92d0f7ddf6bf10\", \"jingle_unittests\": \"41893a802eb4d1231e165d4e9d9573ac3bde1158\", \"latency_unittests\": \"c1791a4205930dad385259b6ccca742d268be9d9\", \"libjingle_xmpp_unittests\": \"442f42b0a4778c2a2982eaf3ff4daaa22b202be0\", \"liburlpattern_unittests\": \"ac3d51af55087e1b1b36770ba730a68cec062f23\", \"media_blink_unittests\": \"524d3e413175b8a656e909a483980b1af79d2374\", \"media_unittests\": \"b2f8de99f7b68e8265eaaca02622feb20c62f59f\", \"message_center_unittests\": \"39ec6de194d7b5d4ec25ccb03385e42f8e80a7b8\", \"midi_unittests\": \"da991984d98ee5e5a22e0be3c78c7f66dbfa6d1e\", \"mojo_core_unittests\": \"6164a63d3a97e88c5488de29a947511650d9ddb6\", \"mojo_unittests\": \"421ac62292058862a2241b907b5d2d6e44d8b8c3\", \"nacl_loader_unittests\": \"bb645b616284133190ddb9365338d328e6cad9f8\", \"native_theme_unittests\": \"b808d9f2f5551bb4a6e8911477e2cf9bfcddb345\", \"net_unittests\": \"fb60fcc5259fb1e92ff6f55d855515d33ce07e02\", \"notification_helper_unittests\": \"5ece36644cc3ebfeec4fdd3e34caf6844df1fc25\", \"pdf_unittests\": \"db22fa1c962d9a16c7fa154a6d99c7e57c94f295\", \"perfetto_unittests\": \"221121a77b311654952ba7004f7a1e0ab8ac4f6d\", \"ppapi_unittests\": \"763e03cf577d129015a73fa8aa5953d83d07cf91\", \"printing_unittests\": \"eb2c98e496f73aa93da28300912192c30db9de57\", \"remoting_unittests\": \"9e9eb81236aa44c2fd8f8fba120a9c6dc596a212\", \"sbox_integration_tests\": \"b4af8e2cf67aae0ac25ac468c0d851bd87659002\", \"sbox_unittests\": \"5cc08a2dc08340d08dfadf9d053e06e8f1db77f8\", \"sbox_validation_tests\": \"08c230eed0264dc056f91aafae35d72671ca6e93\", \"service_manager_unittests\": \"64adccb8eba4cb91071ca925a4a493b7034a5dca\", \"services_unittests\": \"77140826ab39f7edae4beaefc9d8af6b2aa66f79\", \"setup_unittests\": \"792841df6f98f3fcbcaf181f9a0bc986680e6208\", \"shell_dialogs_unittests\": \"39597d4c05a566848977b1eba971564e5de866c7\", \"skia_unittests\": \"e71766b82c3ffedd7d5f1346bcfcc652c01aea06\", \"snapshot_unittests\": \"74b5c940fae8d1906c296f5d2ec9091e6ee2e967\", \"sql_unittests\": \"21d9d341e3f8c238f4275527a15726ae9787019c\", \"storage_unittests\": \"30b9ff16230496953e7d398b74a1cd765f50064d\", \"sync_integration_tests\": \"441c638a77164df2796d0bb747d5e1992c07deee\", \"traffic_annotation_auditor_unittests\": \"197a5a916bb01a47e771facc0c463889faedf3b5\", \"ui_base_unittests\": \"70744f2686f0369ae0ae23dbb5683974036fd92b\", \"ui_touch_selection_unittests\": \"2488e59eaa72d3e647ae2a631b88df0432906a9d\", \"unit_tests\": \"060d9e329aa92dd1691cafcef48c1d9e01febe20\", \"updater_tests\": \"0fdd153225d2f3a0b36e25759ee47616f37b7663\", \"url_unittests\": \"0f7052d1aea19325917f0b7d976d66e2601e1fb5\", \"views_unittests\": \"2e0d4b70cd5d748d769a1346ee0723fb57938a59\", \"viz_unittests\": \"a2e18038876971d70e2f94666f5aafd7e2507e58\", \"vr_common_unittests\": \"0f86c3ad2716fd7868f35f51514030bdbf55f78d\", \"vr_pixeltests\": \"99a816eac93871b0186b4b13e4cb85dac3475c51\", \"weblayer_browsertests\": \"81ff22cbc6c1feb96398c65e2341cd46decf43b2\", \"weblayer_unittests\": \"0263ce764cc58e84fe993e3b525202644f2e5217\", \"wm_unittests\": \"1327ebbbff636bd5de002d60faa7ce643986796e\", \"wtf_unittests\": \"4f009de739fcd554f276ec91240caff6b613a729\", \"zlib_unittests\": \"a224b26400b0d2fb892ddcf15cf8a2bffa9428b8\", \"zucchini_unittests\": \"d1fe0c1925d000d7e54d8d623997d309da85af76\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"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-2-zecp\"], \"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\": [\"5754-8c4f760\"], \"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": "1615564941649491",
"status": "COMPLETED",
"status_changed_ts": "1615568264524696",
"tags": [
"build_address:luci.chromium.ci/win-asan/17773",
"builder:win-asan",
"buildset:commit/git/b3f9ed8e2189fb36fe069fa54e7d504210a38e39",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3f9ed8e2189fb36fe069fa54e7d504210a38e39",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018381168649188448",
"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/8852951106627706432/+/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:523ffdfc6d4f7a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615568264528879",
"url": "https://ci.chromium.org/b/8852951106627706432",
"utcnow_ts": "1615568265124183"
}
}
[16:57:45.133] Invocation finished in 55m39.02841191s with status SUCCEEDED