[09:33:31.025] New invocation is queued and will start shortly
[09:33:32.180] Starting the invocation (attempt 1)
[09:33:32.243] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:33:32.276] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:33:32.276] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021214122256512160",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"2640a1d692707db0ebac31479825ac7aedaf7c27\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021214122256512160",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/2640a1d692707db0ebac31479825ac7aedaf7c27",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2640a1d692707db0ebac31479825ac7aedaf7c27",
"gitiles_ref:refs/heads/master"
]
}
[09:33:34.356] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612863212446108",
"id": "8855784060104532912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2640a1d692707db0ebac31479825ac7aedaf7c27\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612863213920534",
"tags": [
"build_address:luci.chromium.ci/win-asan/17252",
"builder:win-asan",
"buildset:commit/git/2640a1d692707db0ebac31479825ac7aedaf7c27",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2640a1d692707db0ebac31479825ac7aedaf7c27",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021214122256512160",
"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/8855784060104532912/+/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": "1612863213921579",
"url": "https://ci.chromium.org/b/8855784060104532912",
"utcnow_ts": "1612863214331329"
}
}
[09:33:34.356] Task URL: https://ci.chromium.org/b/8855784060104532912
[09:33:34.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:2:0) after 1m0s
[09:33:40.929] Received PubSub notification, asking Buildbucket for the build status
[09:33:41.048] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:34.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:2:0)
[09:34:34.633] Timer tick, asking Buildbucket for the build status
[09:34:34.741] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:34.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:4:0) after 1m0s
[09:35:35.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:4:0)
[09:35:35.085] Timer tick, asking Buildbucket for the build status
[09:35:35.493] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:35.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:5:0) after 1m0s
[09:36:35.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:5:0)
[09:36:35.482] Timer tick, asking Buildbucket for the build status
[09:36:35.593] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:35.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:6:0) after 1m0s
[09:37:35.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:6:0)
[09:37:35.780] Timer tick, asking Buildbucket for the build status
[09:37:36.085] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:36.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:7:0) after 1m0s
[09:38:36.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:7:0)
[09:38:36.173] Timer tick, asking Buildbucket for the build status
[09:38:36.770] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:36.770] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:8:0) after 1m0s
[09:39:36.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:8:0)
[09:39:36.842] Timer tick, asking Buildbucket for the build status
[09:39:37.094] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:37.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:9:0) after 1m0s
[09:40:37.281] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:9:0)
[09:40:37.281] Timer tick, asking Buildbucket for the build status
[09:40:37.549] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:37.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:10:0) after 1m0s
[09:41:37.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:10:0)
[09:41:37.726] Timer tick, asking Buildbucket for the build status
[09:41:37.901] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:37.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:11:0) after 1m0s
[09:42:38.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:11:0)
[09:42:38.269] Timer tick, asking Buildbucket for the build status
[09:42:38.448] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:38.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:12:0) after 1m0s
[09:43:38.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:12:0)
[09:43:38.477] Timer tick, asking Buildbucket for the build status
[09:43:39.250] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:39.250] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:13:0) after 1m0s
[09:44:39.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:13:0)
[09:44:39.379] Timer tick, asking Buildbucket for the build status
[09:44:39.525] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:39.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:14:0) after 1m0s
[09:45:39.612] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:14:0)
[09:45:39.612] Timer tick, asking Buildbucket for the build status
[09:45:40.005] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:40.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:15:0) after 1m0s
[09:46:40.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:15:0)
[09:46:40.178] Timer tick, asking Buildbucket for the build status
[09:46:40.435] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:40.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:16:0) after 1m0s
[09:47:40.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:16:0)
[09:47:40.474] Timer tick, asking Buildbucket for the build status
[09:47:40.730] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:40.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:17:0) after 1m0s
[09:48:40.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:17:0)
[09:48:40.748] Timer tick, asking Buildbucket for the build status
[09:48:41.054] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:41.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:18:0) after 1m0s
[09:49:41.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:18:0)
[09:49:41.101] Timer tick, asking Buildbucket for the build status
[09:49:41.237] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:41.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:19:0) after 1m0s
[09:50:41.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:19:0)
[09:50:41.332] Timer tick, asking Buildbucket for the build status
[09:50:41.505] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:41.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:20:0) after 1m0s
[09:51:41.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:20:0)
[09:51:41.480] Timer tick, asking Buildbucket for the build status
[09:51:41.768] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:41.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:21:0) after 1m0s
[09:52:41.800] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:21:0)
[09:52:41.800] Timer tick, asking Buildbucket for the build status
[09:52:42.000] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:42.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:22:0) after 1m0s
[09:53:42.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:22:0)
[09:53:42.143] Timer tick, asking Buildbucket for the build status
[09:53:42.317] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:42.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:23:0) after 1m0s
[09:54:42.470] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:23:0)
[09:54:42.470] Timer tick, asking Buildbucket for the build status
[09:54:42.744] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:42.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:24:0) after 1m0s
[09:55:42.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:24:0)
[09:55:42.867] Timer tick, asking Buildbucket for the build status
[09:55:43.052] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:43.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:25:0) after 1m0s
[09:56:43.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:25:0)
[09:56:43.534] Timer tick, asking Buildbucket for the build status
[09:56:44.203] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:44.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:26:0) after 1m0s
[09:57:44.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:26:0)
[09:57:44.251] Timer tick, asking Buildbucket for the build status
[09:57:45.444] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:45.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:27:0) after 1m0s
[09:58:45.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:27:0)
[09:58:45.482] Timer tick, asking Buildbucket for the build status
[09:58:45.587] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:45.587] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:28:0) after 1m0s
[09:59:45.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:28:0)
[09:59:45.619] Timer tick, asking Buildbucket for the build status
[09:59:45.772] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:45.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:29:0) after 1m0s
[10:00:45.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:29:0)
[10:00:45.795] Timer tick, asking Buildbucket for the build status
[10:00:45.996] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:45.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:30:0) after 1m0s
[10:01:46.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:30:0)
[10:01:46.084] Timer tick, asking Buildbucket for the build status
[10:01:46.637] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:46.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:31:0) after 1m0s
[10:02:46.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:31:0)
[10:02:46.693] Timer tick, asking Buildbucket for the build status
[10:02:48.946] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:48.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:32:0) after 1m0s
[10:03:48.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:32:0)
[10:03:48.992] Timer tick, asking Buildbucket for the build status
[10:03:49.310] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:49.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:33:0) after 1m0s
[10:04:49.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:33:0)
[10:04:49.371] Timer tick, asking Buildbucket for the build status
[10:04:49.723] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:49.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:34:0) after 1m0s
[10:05:49.802] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:34:0)
[10:05:49.802] Timer tick, asking Buildbucket for the build status
[10:05:49.900] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:49.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:35:0) after 1m0s
[10:06:49.925] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:35:0)
[10:06:49.925] Timer tick, asking Buildbucket for the build status
[10:06:50.033] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:50.033] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:36:0) after 1m0s
[10:07:50.153] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:36:0)
[10:07:50.153] Timer tick, asking Buildbucket for the build status
[10:07:50.557] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:50.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:37:0) after 1m0s
[10:08:50.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:37:0)
[10:08:50.814] Timer tick, asking Buildbucket for the build status
[10:08:51.349] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:51.349] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:38:0) after 1m0s
[10:09:51.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:38:0)
[10:09:51.529] Timer tick, asking Buildbucket for the build status
[10:09:51.688] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:51.688] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:39:0) after 1m0s
[10:10:51.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:39:0)
[10:10:51.776] Timer tick, asking Buildbucket for the build status
[10:10:52.126] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:52.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:40:0) after 1m0s
[10:11:52.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:40:0)
[10:11:52.174] Timer tick, asking Buildbucket for the build status
[10:11:52.239] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:52.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:41:0) after 1m0s
[10:12:52.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:41:0)
[10:12:52.309] Timer tick, asking Buildbucket for the build status
[10:12:52.421] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:52.421] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:42:0) after 1m0s
[10:13:52.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:42:0)
[10:13:52.430] Timer tick, asking Buildbucket for the build status
[10:13:52.883] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:52.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:43:0) after 1m0s
[10:14:52.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:43:0)
[10:14:52.902] Timer tick, asking Buildbucket for the build status
[10:14:52.970] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:52.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:44:0) after 1m0s
[10:15:53.079] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:44:0)
[10:15:53.079] Timer tick, asking Buildbucket for the build status
[10:15:53.272] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:53.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:45:0) after 1m0s
[10:16:53.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:45:0)
[10:16:53.368] Timer tick, asking Buildbucket for the build status
[10:16:53.483] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:53.483] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:46:0) after 1m0s
[10:17:53.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:46:0)
[10:17:53.482] Timer tick, asking Buildbucket for the build status
[10:17:53.610] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:53.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:47:0) after 1m0s
[10:18:53.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:47:0)
[10:18:53.733] Timer tick, asking Buildbucket for the build status
[10:18:54.070] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:18:54.070] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:48:0) after 1m0s
[10:19:54.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:48:0)
[10:19:54.081] Timer tick, asking Buildbucket for the build status
[10:19:54.725] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:19:54.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:49:0) after 1m0s
[10:20:54.844] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:49:0)
[10:20:54.844] Timer tick, asking Buildbucket for the build status
[10:20:54.933] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:20:54.933] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:50:0) after 1m0s
[10:21:54.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:50:0)
[10:21:54.958] Timer tick, asking Buildbucket for the build status
[10:21:55.127] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:21:55.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:51:0) after 1m0s
[10:22:55.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:51:0)
[10:22:55.134] Timer tick, asking Buildbucket for the build status
[10:22:55.212] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:22:55.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:52:0) after 1m0s
[10:23:55.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:52:0)
[10:23:55.260] Timer tick, asking Buildbucket for the build status
[10:23:55.378] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:23:55.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:53:0) after 1m0s
[10:24:55.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:53:0)
[10:24:55.436] Timer tick, asking Buildbucket for the build status
[10:24:55.571] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:24:55.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:54:0) after 1m0s
[10:25:55.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:54:0)
[10:25:55.599] Timer tick, asking Buildbucket for the build status
[10:25:55.696] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:25:55.696] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:55:0) after 1m0s
[10:26:55.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:55:0)
[10:26:55.717] Timer tick, asking Buildbucket for the build status
[10:26:56.490] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:26:56.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:56:0) after 1m0s
[10:27:57.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:56:0)
[10:27:57.854] Timer tick, asking Buildbucket for the build status
[10:27:57.966] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:27:57.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:57:0) after 1m0s
[10:28:58.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:57:0)
[10:28:58.056] Timer tick, asking Buildbucket for the build status
[10:28:58.187] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:28:58.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:58:0) after 1m0s
[10:29:58.278] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:58:0)
[10:29:58.278] Timer tick, asking Buildbucket for the build status
[10:29:58.357] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:29:58.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:59:0) after 1m0s
[10:30:58.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:59:0)
[10:30:58.491] Timer tick, asking Buildbucket for the build status
[10:30:58.659] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:58.659] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:60:0) after 1m0s
[10:31:58.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:60:0)
[10:31:58.681] Timer tick, asking Buildbucket for the build status
[10:31:58.753] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:58.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:61:0) after 1m0s
[10:32:58.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:61:0)
[10:32:58.788] Timer tick, asking Buildbucket for the build status
[10:32:59.123] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:59.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:62:0) after 1m0s
[10:33:59.244] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:62:0)
[10:33:59.244] Timer tick, asking Buildbucket for the build status
[10:33:59.507] Build 8855784060104532912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:59.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021214122256512160:63:0) after 1m0s
[10:34:06.842] Received PubSub notification, asking Buildbucket for the build status
[10:34:06.914] Build 8855784060104532912: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:34:06.914] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612866844509026",
"created_by": "project:chromium",
"created_ts": "1612863212446108",
"id": "8855784060104532912",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"2640a1d692707db0ebac31479825ac7aedaf7c27\"}}",
"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\": \"2640a1d692707db0ebac31479825ac7aedaf7c27\", \"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-8-jw2s\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17252, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"281c65523a0a57d27caaabe4d1c7209026e12862\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"2640a1d692707db0ebac31479825ac7aedaf7c27\", \"got_revision_cp\": \"refs/heads/master@{#852136}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"9d35d544ce966098fdcb36107835d57a4dc1f32d\", \"got_v8_revision\": \"b19b2448ed8ecdc8965621750ac648a2331c0c18\", \"got_v8_revision_cp\": \"refs/heads/9.0.144@{#1}\", \"got_webrtc_revision\": \"54ea85c2cd3a2cb1b8f440a71aa9676a9b88135c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33190}\", \"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\": \"2640a1d692707db0ebac31479825ac7aedaf7c27\", \"swarm_hashes_refs/heads/master(at){#852136}_without_patch\": {\"absl_hardening_tests\": \"f331f07e4f044c08d5c0d20f693676c4c1280438\", \"accessibility_unittests\": \"366d79ec42f1e389ea06e1518d8fbcdb2045eec1\", \"angle_unittests\": \"954db0928a7dc7edb9fff057812ccd80b3a3afd6\", \"app_shell_unittests\": \"35975d96429a4b878659535861bdc37efd8eafc0\", \"aura_unittests\": \"c361011e64e200841c4808087bfb721ea6e42ab1\", \"base_unittests\": \"9fc6f3a9fa948a1b6e0ba242b10bad662d586379\", \"base_util_unittests\": \"7836d726d566757266ca0f74aa0b8e9ceabffe6f\", \"blink_common_unittests\": \"ee54f7f5cefb303f2843e3fca7e94b70d56dfe6f\", \"blink_fuzzer_unittests\": \"e23d430d28aeb3b6a5101824640fe43971c3cd52\", \"blink_heap_unittests\": \"c95cca4fe42b52ce7d6891d60397a2f3905f4091\", \"blink_platform_unittests\": \"c1c829fea2e9f34684db9f7b864a8bc3f022a490\", \"blink_unittests\": \"a86d477a49fbc2c3cd3d8acd9ed0e9ad569ca262\", \"boringssl_crypto_tests\": \"911304cf904d33614972214a63a87322a1e112a0\", \"boringssl_ssl_tests\": \"9475bd0d9cbfe45413a1c9d5ccc8c06685e90752\", \"browser_switcher_bho_unittests\": \"7058291fe52e761a0205ec7b04f1597874ca0236\", \"browser_tests\": \"e88b70cc9f950c54787bb6a1cef06fc206da5014\", \"capture_unittests\": \"c09ee383096f0cece0ab7a06f9d45f5bc119a844\", \"cast_unittests\": \"f58fea5a5f6bd8d71d35349cc3f0dc0d025bdeab\", \"cc_unittests\": \"3b36359fc5f1b8d0cdc9f0a6a53479db8203087b\", \"chrome_app_unittests\": \"be00229e751c73566aad262b1438318707b999a8\", \"chrome_cleaner_unittests\": \"067a8f5883ce21805e8d916c217aa1055269cb04\", \"chrome_elf_unittests\": \"18379c71ec800cf4866e717a6c489857baf987c8\", \"chromedriver_unittests\": \"e55e46a2cc03495e9511584a9931e193365de46b\", \"components_browsertests\": \"064d9eae62425cde4d4cf3f32c757939983ab4bc\", \"components_unittests\": \"8dd773a0fb01c18583856010cd574a630cf2bb8e\", \"compositor_unittests\": \"b23c19198033d0f124b4ce302ea1d7c28a3b5320\", \"content_browsertests\": \"709486db41a9deca6c271297e479ed4797a9b176\", \"content_unittests\": \"cff1f23f2ef9d9391ba8046fcc3ca630d263e5a4\", \"courgette_unittests\": \"87d32dea05815b1174d2e84749c4d9cacc6ab5b2\", \"crashpad_tests\": \"6f60c714bad4b25fe8ef51a684123dba85e35e03\", \"cronet_tests\": \"1b1e74f82a0555e71f97482123e504ef4e57434c\", \"cronet_unittests\": \"9aef8fa3f8ddced33885a380feafc95b290cbb52\", \"crypto_unittests\": \"0f201dd55aef6809f632c65053c1e37bd82b26b4\", \"delayloads_unittests\": \"684ac73e640476819edcb8b1609d5d345b118ec1\", \"device_unittests\": \"fbbe566c05e258d3e8141b0ae568d71f84deb933\", \"display_unittests\": \"1a05b2fb5d7ccd03646c9ac5444af9b6843b2461\", \"elevation_service_unittests\": \"41330b6128577f8053975c0b6f4d34f52889f91d\", \"events_unittests\": \"2cded6be821096f37a839dbe0a8d699812c344f4\", \"extensions_browsertests\": \"a908b487f3ad61d4991f835499a13e1b50c66c8b\", \"extensions_unittests\": \"51e27b58a62f841d54f9cff864c0321862326ca6\", \"filesystem_service_unittests\": \"9ec2b7db0717684d76b80bd51f7d16e14c098187\", \"gcm_unit_tests\": \"22eb20b15a0bd2bf78ae8dcd76e5b992ce436712\", \"gcp_unittests\": \"bcb3fa4573aad474e796b9fa2a1ec6e86a08ed4b\", \"gfx_unittests\": \"14b7c664f7e9ebea93cd2d3d9c03c2790d5f8b5f\", \"gin_unittests\": \"0ec65cae1ed9ded1be84b2c0e27f5945b7073e44\", \"google_apis_unittests\": \"ccd07a19351e98b84f33f8d96cb17299ade7897d\", \"gpu_unittests\": \"c126fb6ee415dc3f986419ffa70363b83335f1f0\", \"gwp_asan_unittests\": \"8d76535ef664a4c41ce319e9c2134ebbe3715c83\", \"headless_browsertests\": \"6702373cb7f7e59504aa57c3df401010231de5dd\", \"headless_unittests\": \"323fcc4f35dc7b90352ac8f7098c72095eb266a8\", \"install_static_unittests\": \"99636eeb7242f3f49034460fbbbdf7dd0f34829e\", \"installer_util_unittests\": \"238503ecd3ab83d0194bccfc168a8ea4fa03ab4b\", \"interactive_ui_tests\": \"7c22dc857e7ffa68b3d1b6cb5aaab17df3ec325b\", \"ipc_tests\": \"099f0ae01ad3960efb110e173cf4d35fb6f2c455\", \"jingle_unittests\": \"303969355d70c69ae1ff7d3b9b5d77a45230434b\", \"latency_unittests\": \"0880dd5c9c98c0176329154c83633c37bdf5cfc3\", \"libjingle_xmpp_unittests\": \"0d71a6aba8bbae36633a43291510bbca0c27b3a5\", \"liburlpattern_unittests\": \"ad593f62064d240095e5074f003b80fab4134ca8\", \"media_blink_unittests\": \"7477818333f4ff5ee63a12c81dcc4ce0642e5cf2\", \"media_unittests\": \"6dd6108e8a612e3bddf8828001d6dd6e28384869\", \"message_center_unittests\": \"005daef64a17e5389c13e98dc979c2e2289a22bb\", \"midi_unittests\": \"0696ff047d751677bdbafdb867d50f4345176680\", \"mojo_core_unittests\": \"ba6590fa2625f65a18c0454c12cfec227e1d6eda\", \"mojo_unittests\": \"8e84d4e4b87aeccc84b53c75deb690d69dae3006\", \"nacl_loader_unittests\": \"71419db4cdd480a88687f042adbcec95ee0ebf10\", \"native_theme_unittests\": \"6b6cfc18233812f483ae074b91198dc334dce27f\", \"net_unittests\": \"ebfb394b95732d2e748c00bf37e2cbd1f11aff96\", \"notification_helper_unittests\": \"63d95f41f1034d0985aa985d35e0d31babff5cf4\", \"pdf_unittests\": \"73867a3cd0b55726d96f40c405f66bdd219a36e7\", \"perfetto_unittests\": \"f543f582118a9110adbf9eef37e24c5f1068ab5c\", \"ppapi_unittests\": \"607005874e2bf88519753aa598f6fda6734aa08b\", \"printing_unittests\": \"ee03c0bb3e5bf754f9acfbb9c7f4b9960562cfae\", \"remoting_unittests\": \"a886cfc81aff9557fed4dac5e81d94ae42d9fa81\", \"sbox_integration_tests\": \"d54c43f26216b11f7b347418b866628090d973cd\", \"sbox_unittests\": \"a7ffac1c2bdf296e359ff62247d4f54ef97d07e6\", \"sbox_validation_tests\": \"169a995a596dd8941fabb1b723a9aa340679f19c\", \"service_manager_unittests\": \"c31259474e3c40069093f6f4dc3db2ea48bf78a4\", \"services_unittests\": \"a03ccf19acb5ce07001746ce62fa182dd5e193ec\", \"setup_unittests\": \"0e14d34b78cce409c4d237b4f5d261711777b9a9\", \"shell_dialogs_unittests\": \"163898678f258fe0d25a74a4dc3fbde26c354c6c\", \"skia_unittests\": \"75d493cbd0500c427fc9506aef3342f357cab9c4\", \"snapshot_unittests\": \"70f79e057066e06021c8f0abe8688227dca34f84\", \"sql_unittests\": \"1bf0618e639208cdc4fb05c781b4560723a1ebb6\", \"storage_unittests\": \"3be4b7e5fec2351417cadd734b48ef9d5dcb34eb\", \"sync_integration_tests\": \"54c331cc6192d0260a80fbd0d4796614a11f1496\", \"traffic_annotation_auditor_unittests\": \"18abf95296726d10437c3c33a66df746c9287465\", \"ui_base_unittests\": \"2526193767e0dbee765973aee791ed9f22d31421\", \"ui_touch_selection_unittests\": \"a3e956267fe6886ac03620416ed0150eb9f90077\", \"unit_tests\": \"f9d2ef57c015e2ead1dcf18c06b82da2ff9f8e76\", \"updater_tests\": \"9a42cb892afbd6ac85c0415d40b50ccc92c324a6\", \"url_unittests\": \"f1fa5f09d62680717cdab275a559092e75f0af14\", \"views_unittests\": \"2d8b49edf2284241e39affc7d17b7932260a0825\", \"viz_unittests\": \"fcd01b7a0915bab480b94aa8c8d32a162f52d053\", \"vr_common_unittests\": \"4a4fc460c1cae06860457ea7d8a016a85b03a39c\", \"vr_pixeltests\": \"aba4e71a0608da64f298c694e560860cc1f89c0d\", \"weblayer_browsertests\": \"e8fb11eb278ab8da87ed50708b149a9da3c076a4\", \"weblayer_unittests\": \"99bde4d6994df528874e3941412060c939cc7d07\", \"wm_unittests\": \"eb5d4730b5fe7dec2f0cc7caf8c8038e07f33bdc\", \"wtf_unittests\": \"115c836741917739794552228f100efb3e8f460b\", \"zlib_unittests\": \"1e93b0009accfca348e2bfab05834c119feed9dd\", \"zucchini_unittests\": \"d3db0455926873c5516fdd54f258d610aa865ff0\"}}, \"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-8-jw2s\"], \"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\": [\"5685-1877293\"], \"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": "1612863220095824",
"status": "COMPLETED",
"status_changed_ts": "1612866844924686",
"tags": [
"build_address:luci.chromium.ci/win-asan/17252",
"builder:win-asan",
"buildset:commit/git/2640a1d692707db0ebac31479825ac7aedaf7c27",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/2640a1d692707db0ebac31479825ac7aedaf7c27",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021214122256512160",
"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/8855784060104532912/+/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:519ef51e89f77710",
"user_agent:luci-scheduler"
],
"updated_ts": "1612866845005878",
"url": "https://ci.chromium.org/b/8855784060104532912",
"utcnow_ts": "1612866846905991"
}
}
[10:34:06.914] Invocation finished in 1h0m35.903916271s with status SUCCEEDED