[19:20:58.841] New invocation is queued and will start shortly
[19:20:59.902] Starting the invocation (attempt 1)
[19:20:59.937] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:20:59.937] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:20:59.937] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019637013815932288",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8ff376cf6d21b8ccbf85848b96e1eecd7425f48f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019637013815932288",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8ff376cf6d21b8ccbf85848b96e1eecd7425f48f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ff376cf6d21b8ccbf85848b96e1eecd7425f48f",
"gitiles_ref:refs/heads/master"
]
}
[19:21:00.825] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614367260038449",
"id": "8854206951896251392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ff376cf6d21b8ccbf85848b96e1eecd7425f48f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614367260671290",
"tags": [
"build_address:luci.chromium.ci/win-asan/17551",
"builder:win-asan",
"buildset:commit/git/8ff376cf6d21b8ccbf85848b96e1eecd7425f48f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ff376cf6d21b8ccbf85848b96e1eecd7425f48f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019637013815932288",
"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/8854206951896251392/+/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": "1614367260671401",
"url": "https://ci.chromium.org/b/8854206951896251392",
"utcnow_ts": "1614367260811369"
}
}
[19:21:00.825] Task URL: https://ci.chromium.org/b/8854206951896251392
[19:21:00.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:2:0) after 1m0s
[19:21:06.372] Received PubSub notification, asking Buildbucket for the build status
[19:21:06.620] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:02.078] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:2:0)
[19:22:02.078] Timer tick, asking Buildbucket for the build status
[19:22:02.145] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:02.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:4:0) after 1m0s
[19:23:02.202] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:4:0)
[19:23:02.202] Timer tick, asking Buildbucket for the build status
[19:23:02.286] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:02.286] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:5:0) after 1m0s
[19:24:02.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:5:0)
[19:24:02.390] Timer tick, asking Buildbucket for the build status
[19:24:02.468] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:02.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:6:0) after 1m0s
[19:25:02.476] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:6:0)
[19:25:02.476] Timer tick, asking Buildbucket for the build status
[19:25:02.562] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:02.562] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:7:0) after 1m0s
[19:26:02.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:7:0)
[19:26:02.627] Timer tick, asking Buildbucket for the build status
[19:26:02.956] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:02.956] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:8:0) after 1m0s
[19:27:03.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:8:0)
[19:27:03.020] Timer tick, asking Buildbucket for the build status
[19:27:03.148] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:03.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:9:0) after 1m0s
[19:28:03.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:9:0)
[19:28:03.156] Timer tick, asking Buildbucket for the build status
[19:28:03.275] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:03.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:10:0) after 1m0s
[19:29:03.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:10:0)
[19:29:03.354] Timer tick, asking Buildbucket for the build status
[19:29:03.563] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:03.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:11:0) after 1m0s
[19:30:03.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:11:0)
[19:30:03.633] Timer tick, asking Buildbucket for the build status
[19:30:03.746] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:03.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:12:0) after 1m0s
[19:31:03.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:12:0)
[19:31:03.757] Timer tick, asking Buildbucket for the build status
[19:31:03.893] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:03.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:13:0) after 1m0s
[19:32:03.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:13:0)
[19:32:03.888] Timer tick, asking Buildbucket for the build status
[19:32:04.113] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:04.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:14:0) after 1m0s
[19:33:04.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:14:0)
[19:33:04.220] Timer tick, asking Buildbucket for the build status
[19:33:04.311] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:04.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:15:0) after 1m0s
[19:34:04.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:15:0)
[19:34:04.324] Timer tick, asking Buildbucket for the build status
[19:34:04.415] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:04.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:16:0) after 1m0s
[19:35:04.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:16:0)
[19:35:04.510] Timer tick, asking Buildbucket for the build status
[19:35:04.602] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:04.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:17:0) after 1m0s
[19:36:04.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:17:0)
[19:36:04.786] Timer tick, asking Buildbucket for the build status
[19:36:04.870] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:04.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:18:0) after 1m0s
[19:37:05.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:18:0)
[19:37:05.047] Timer tick, asking Buildbucket for the build status
[19:37:05.131] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:05.131] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:19:0) after 1m0s
[19:38:05.193] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:19:0)
[19:38:05.193] Timer tick, asking Buildbucket for the build status
[19:38:05.303] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:05.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:20:0) after 1m0s
[19:39:05.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:20:0)
[19:39:05.536] Timer tick, asking Buildbucket for the build status
[19:39:05.612] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:05.612] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:21:0) after 1m0s
[19:40:05.646] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:21:0)
[19:40:05.646] Timer tick, asking Buildbucket for the build status
[19:40:05.732] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:05.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:22:0) after 1m0s
[19:41:06.131] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:22:0)
[19:41:06.131] Timer tick, asking Buildbucket for the build status
[19:41:06.217] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:06.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:23:0) after 1m0s
[19:42:06.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:23:0)
[19:42:06.339] Timer tick, asking Buildbucket for the build status
[19:42:06.435] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:06.435] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:24:0) after 1m0s
[19:43:06.484] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:24:0)
[19:43:06.484] Timer tick, asking Buildbucket for the build status
[19:43:06.665] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:06.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:25:0) after 1m0s
[19:44:06.722] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:25:0)
[19:44:06.722] Timer tick, asking Buildbucket for the build status
[19:44:06.860] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:06.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:26:0) after 1m0s
[19:45:06.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:26:0)
[19:45:06.888] Timer tick, asking Buildbucket for the build status
[19:45:07.050] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:07.050] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:27:0) after 1m0s
[19:46:07.162] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:27:0)
[19:46:07.162] Timer tick, asking Buildbucket for the build status
[19:46:07.276] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:07.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:28:0) after 1m0s
[19:47:07.297] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:28:0)
[19:47:07.297] Timer tick, asking Buildbucket for the build status
[19:47:07.407] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:07.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:29:0) after 1m0s
[19:48:07.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:29:0)
[19:48:07.439] Timer tick, asking Buildbucket for the build status
[19:48:07.566] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:07.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:30:0) after 1m0s
[19:49:07.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:30:0)
[19:49:07.734] Timer tick, asking Buildbucket for the build status
[19:49:08.095] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:08.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:31:0) after 1m0s
[19:50:08.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:31:0)
[19:50:08.143] Timer tick, asking Buildbucket for the build status
[19:50:08.561] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:08.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:32:0) after 1m0s
[19:51:08.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:32:0)
[19:51:08.626] Timer tick, asking Buildbucket for the build status
[19:51:08.723] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:08.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:33:0) after 1m0s
[19:52:08.772] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:33:0)
[19:52:08.772] Timer tick, asking Buildbucket for the build status
[19:52:08.861] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:08.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:34:0) after 1m0s
[19:53:08.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:34:0)
[19:53:08.897] Timer tick, asking Buildbucket for the build status
[19:53:09.003] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:09.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:35:0) after 1m0s
[19:54:09.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:35:0)
[19:54:09.008] Timer tick, asking Buildbucket for the build status
[19:54:09.451] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:09.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:36:0) after 1m0s
[19:55:09.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:36:0)
[19:55:09.433] Timer tick, asking Buildbucket for the build status
[19:55:09.516] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:09.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:37:0) after 1m0s
[19:56:09.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:37:0)
[19:56:09.516] Timer tick, asking Buildbucket for the build status
[19:56:09.645] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:09.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:38:0) after 1m0s
[19:57:09.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:38:0)
[19:57:09.670] Timer tick, asking Buildbucket for the build status
[19:57:09.761] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:09.762] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:39:0) after 1m0s
[19:58:09.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:39:0)
[19:58:09.818] Timer tick, asking Buildbucket for the build status
[19:58:09.953] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:09.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:40:0) after 1m0s
[19:59:09.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:40:0)
[19:59:09.970] Timer tick, asking Buildbucket for the build status
[19:59:10.064] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:10.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:41:0) after 1m0s
[20:00:10.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:41:0)
[20:00:10.113] Timer tick, asking Buildbucket for the build status
[20:00:10.880] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:10.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:42:0) after 1m0s
[20:01:10.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:42:0)
[20:01:10.999] Timer tick, asking Buildbucket for the build status
[20:01:11.132] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:11.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:43:0) after 1m0s
[20:02:11.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:43:0)
[20:02:11.336] Timer tick, asking Buildbucket for the build status
[20:02:11.479] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:11.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:44:0) after 1m0s
[20:03:11.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:44:0)
[20:03:11.666] Timer tick, asking Buildbucket for the build status
[20:03:11.746] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:11.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:45:0) after 1m0s
[20:04:11.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:45:0)
[20:04:11.926] Timer tick, asking Buildbucket for the build status
[20:04:12.103] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:04:12.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:46:0) after 1m0s
[20:05:12.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:46:0)
[20:05:12.269] Timer tick, asking Buildbucket for the build status
[20:05:12.364] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:05:12.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:47:0) after 1m0s
[20:06:12.471] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:47:0)
[20:06:12.471] Timer tick, asking Buildbucket for the build status
[20:06:12.588] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:06:12.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:48:0) after 1m0s
[20:07:12.747] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:48:0)
[20:07:12.747] Timer tick, asking Buildbucket for the build status
[20:07:12.843] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:07:12.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:49:0) after 1m0s
[20:08:12.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:49:0)
[20:08:12.893] Timer tick, asking Buildbucket for the build status
[20:08:12.974] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:08:12.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:50:0) after 1m0s
[20:09:13.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:50:0)
[20:09:13.119] Timer tick, asking Buildbucket for the build status
[20:09:13.259] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:09:13.259] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:51:0) after 1m0s
[20:10:13.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:51:0)
[20:10:13.269] Timer tick, asking Buildbucket for the build status
[20:10:13.405] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:10:13.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:52:0) after 1m0s
[20:11:13.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:52:0)
[20:11:13.463] Timer tick, asking Buildbucket for the build status
[20:11:13.552] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:11:13.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:53:0) after 1m0s
[20:12:13.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:53:0)
[20:12:13.592] Timer tick, asking Buildbucket for the build status
[20:12:13.679] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:12:13.679] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:54:0) after 1m0s
[20:13:13.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:54:0)
[20:13:13.781] Timer tick, asking Buildbucket for the build status
[20:13:13.951] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:13:13.951] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:55:0) after 1m0s
[20:14:14.536] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:55:0)
[20:14:14.536] Timer tick, asking Buildbucket for the build status
[20:14:14.630] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:14:14.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:56:0) after 1m0s
[20:15:14.984] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:56:0)
[20:15:14.984] Timer tick, asking Buildbucket for the build status
[20:15:15.086] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:15:15.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:57:0) after 1m0s
[20:16:15.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:57:0)
[20:16:15.201] Timer tick, asking Buildbucket for the build status
[20:16:15.290] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:16:15.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:58:0) after 1m0s
[20:17:15.311] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:58:0)
[20:17:15.311] Timer tick, asking Buildbucket for the build status
[20:17:15.605] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:17:15.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:59:0) after 1m0s
[20:18:15.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:59:0)
[20:18:15.727] Timer tick, asking Buildbucket for the build status
[20:18:15.901] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:18:15.901] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:60:0) after 1m0s
[20:19:15.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:60:0)
[20:19:15.977] Timer tick, asking Buildbucket for the build status
[20:19:16.159] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:19:16.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:61:0) after 1m0s
[20:20:16.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:61:0)
[20:20:16.203] Timer tick, asking Buildbucket for the build status
[20:20:16.501] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:20:16.501] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:62:0) after 1m0s
[20:21:16.539] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:62:0)
[20:21:16.539] Timer tick, asking Buildbucket for the build status
[20:21:16.646] Build 8854206951896251392: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:21:16.646] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019637013815932288:63:0) after 1m0s
[20:22:02.382] Received PubSub notification, asking Buildbucket for the build status
[20:22:02.825] Build 8854206951896251392: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:22:02.825] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614370921496081",
"created_by": "project:chromium",
"created_ts": "1614367260038449",
"id": "8854206951896251392",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8ff376cf6d21b8ccbf85848b96e1eecd7425f48f\"}}",
"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\": \"8ff376cf6d21b8ccbf85848b96e1eecd7425f48f\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17551, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"23462996035db5276d7afc60fd023d95ddc5e996\", \"got_dawn_revision\": \"329c1c98fef964ea532a0579ae6f2553ff3382c5\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8ff376cf6d21b8ccbf85848b96e1eecd7425f48f\", \"got_revision_cp\": \"refs/heads/master@{#858212}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"349abccec8f7ebd1259de11965d58790a76b0ba4\", \"got_v8_revision\": \"bd0108b4c88e0d6f2350cb79b5f363fbd02f3eb7\", \"got_v8_revision_cp\": \"refs/heads/9.0.257@{#1}\", \"got_webrtc_revision\": \"376cf384ac689eca0bf8d4d1d3a3cf80888d3fbb\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33348}\", \"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\": \"8ff376cf6d21b8ccbf85848b96e1eecd7425f48f\", \"swarm_hashes_refs/heads/master(at){#858212}_without_patch\": {\"absl_hardening_tests\": \"22c4ef32ad78da5446eb11905d1993c0417e7eed\", \"accessibility_unittests\": \"90a1a3e8cfb13e1dc01539b621c9017637f9fbe3\", \"angle_unittests\": \"5d967be4f799ee03a122c41594cb2056fc9b6b06\", \"app_shell_unittests\": \"578eee32fe9595b4c2fc624b5891e27c8a8a8f74\", \"aura_unittests\": \"2d3959dcead6c0838d09cedba572f36bb3b6827d\", \"base_unittests\": \"aef2a88a30801a08fd3f1c52888ce60475dfb081\", \"base_util_unittests\": \"5c5d664f884d27dca7ae902729254b963d484cb0\", \"blink_common_unittests\": \"8b81302046aebba3e05809685c15b02a26b88beb\", \"blink_fuzzer_unittests\": \"6d144cd5e81b1eee468392ef8d1cd8c9fc36534e\", \"blink_heap_unittests\": \"71725c686279fff88d9bf2b7c1f5a4a1bea2fd58\", \"blink_platform_unittests\": \"2b09f110cef798abedd7ce982c11a689395902ee\", \"blink_unittests\": \"cd07d46b28e95736ff32dbca24cbf22604c8c4df\", \"boringssl_crypto_tests\": \"7109778fe3bf46ef6dfa774d9d9835e80c4f0b8c\", \"boringssl_ssl_tests\": \"ea49708f3da9f8ac0f481bdb9f980a8e168d8df6\", \"browser_switcher_bho_unittests\": \"6be71c67fc664be6b83dac7f83ef0f1f252c3073\", \"browser_tests\": \"ac4c6b934f7f775334912f987983b3fc638067f1\", \"capture_unittests\": \"75ec6863812813f0258d63b594d33c10f44d45c5\", \"cast_unittests\": \"78dcb6100167f1b9e5866e8840f9d6daf492c8ea\", \"cc_unittests\": \"b09dd82a73fa1b454e646fe28aee70618a606040\", \"chrome_app_unittests\": \"422acd5349aa73be3a3219398f3427e2fe690177\", \"chrome_cleaner_unittests\": \"66824e0e2907da0f97a852a0f1139e9b14a6193f\", \"chrome_elf_unittests\": \"69cb8c45b622ad538ce9fbc47e3775278539f5ec\", \"chromedriver_unittests\": \"8c94ae6fce98a7c3f4bcc36aae2e6a1b2bcee531\", \"color_unittests\": \"a784918f9ec53553793cd587fc6a056e24549309\", \"components_browsertests\": \"85f518b5de198e0ddac65748183953d6ae1df968\", \"components_unittests\": \"7e9bccaa6d0dc4216e2c5138d150a5d46dad6542\", \"compositor_unittests\": \"2086435d3fcb8fd0461bd374e393b4289dce7618\", \"content_browsertests\": \"342fd39cbaca8490b45fb116e61a622ca2b722ee\", \"content_unittests\": \"7403deeef99fa43251d1c3d9f15e00c4ae682829\", \"courgette_unittests\": \"71824731a73b3376672b4fe332073578414dfabe\", \"crashpad_tests\": \"9d163eb1c75c851c51b15d7e16d5962174c6c31b\", \"cronet_tests\": \"7841c748486b25207a975d5cf9d49baa20d9417f\", \"cronet_unittests\": \"a2477c197c4b00c08fb158fdee6dde0d9c0122fd\", \"crypto_unittests\": \"788bc7590f583350b7a43cdb4902ab096e019e92\", \"delayloads_unittests\": \"e9bd32e50233c981a1c77ed6cb7d6dcc0ef9a502\", \"device_unittests\": \"804f520742d46c5a1039f87bd7f5a94b0b3107fd\", \"display_unittests\": \"cbadda9eb156e7c940939a19442dbb245aeaade2\", \"elevation_service_unittests\": \"35be576f9f708ce883c7081d268523b2da0dcd44\", \"events_unittests\": \"2b5100f0ca379f356b050b20f16fd03a70aa43d1\", \"extensions_browsertests\": \"3ac818f9de13bc63d1260d0a5a8848d6c16b39d4\", \"extensions_unittests\": \"7eac2ae175e4cabbd23fc2adfd34509698f0e238\", \"filesystem_service_unittests\": \"dfdd85d2be38c2484d5dc69a829b0953a861b8e9\", \"gcm_unit_tests\": \"e774b68feadfe9341fdec96552f0f5e7f6af3d6b\", \"gcp_unittests\": \"080752129fcc73e2ee11e3e6cd2d49ce49816100\", \"gfx_unittests\": \"6bb370396a7782998d824e1e75b1d0a259bbc0ef\", \"gin_unittests\": \"49ec16f50a792a9a4c65f804ca58aa2fc4796236\", \"google_apis_unittests\": \"2bb29c10a6c050078e70215a4f7b72b8057cb49b\", \"gpu_unittests\": \"75d6e7d67154b86ed10e289f4f6302babdfbf894\", \"gwp_asan_unittests\": \"745f51923ff210837bc774888c9074370d2f24d0\", \"headless_browsertests\": \"255e0fbe9ff9c67d037f2b1e75c7e40eaaa10814\", \"headless_unittests\": \"5aeaa684c48ea079465a300e837d530833f52f7f\", \"install_static_unittests\": \"e5a1f5f7ddadedbbed483dd188adc0d0172aa7cd\", \"installer_util_unittests\": \"b37e28d1871ff12ec344d79afe68d3c56345c5bd\", \"interactive_ui_tests\": \"41063036ab9c26e44c085c90c5b4ddd32afaf3fb\", \"ipc_tests\": \"c3c0184afeb68fc6622ddbb2105b55e40c584fcc\", \"jingle_unittests\": \"b8860c19c39f28caccfee20cf2c8f025add12dff\", \"latency_unittests\": \"2ad88e151dc55708cde67f7920dcc100f4198964\", \"libjingle_xmpp_unittests\": \"2ee5a8568c7bb86fa027823380eeb7848e0558b4\", \"liburlpattern_unittests\": \"7e3dcfd1a0460c6be5aefecb5c9f0e4ccef7aed0\", \"media_blink_unittests\": \"c8959c8b515470128d39e20c23e7b137a6e57c12\", \"media_unittests\": \"bde7f80ee1a582049b70ebae661efe04d8cb9740\", \"message_center_unittests\": \"891359ac0a4ffa611e6bdd1316d39632822d18f7\", \"midi_unittests\": \"d39ebcaf709f0d9444c94a64d4fa827d974c944e\", \"mojo_core_unittests\": \"7838cb0ba0393697b96eae3bcd77cfde9a7accc4\", \"mojo_unittests\": \"0d6e356dcb319fc0d5a3bea67c1953e742d3305d\", \"nacl_loader_unittests\": \"04aa8c6a0a920fb1f0407a22c517b0192b7f4f41\", \"native_theme_unittests\": \"9b51e972cc3df2a3e99faebc8ef8335dc39d6eef\", \"net_unittests\": \"1caddae1730b291eb84a596b467ad422e37b256e\", \"notification_helper_unittests\": \"635460e713dc384c92a9a5a5d1055d6a94b47e28\", \"pdf_unittests\": \"d9e2d98bcce9f63bb045b57242a9c2ff081a8ada\", \"perfetto_unittests\": \"6a3c3a0a55230bde3d9af2975c7b1a7c2ae7d026\", \"ppapi_unittests\": \"5b5b1c755bb29efb26755851bd3687b53009b7da\", \"printing_unittests\": \"2a634ed4ec026d711c65caea44f4f49727310307\", \"remoting_unittests\": \"d4cb5f3b573801219b00f45ad7546825ace95239\", \"sbox_integration_tests\": \"c93b33e0f7bcac99abb2c16f08a8e7e38e3bcb02\", \"sbox_unittests\": \"6b17a59e8ed88a43179297c71f14d1c9a7b559dd\", \"sbox_validation_tests\": \"45b615d199e250bbfb089a1d4aada150139c1a5e\", \"service_manager_unittests\": \"937fbdcc6927c87644e0af18cfc6a70926ea43a6\", \"services_unittests\": \"98aa66dba29556daea6430bb7f81ae979ca067fc\", \"setup_unittests\": \"e8b8527368da72b60e387d8fe49491c17ab3bc14\", \"shell_dialogs_unittests\": \"c56254a56e60d706768907371fefddbd5578d22c\", \"skia_unittests\": \"2dd2ba4db5461208960955d1a62bf690ff87fe35\", \"snapshot_unittests\": \"27a767953c19b80dfc30e8fe9c4f8eae08c72291\", \"sql_unittests\": \"0a52efcfc3619c26e690415644d0b47074d8fec2\", \"storage_unittests\": \"95b580e3e5ecdbb74273910613c8d6d50e7edcff\", \"sync_integration_tests\": \"048b060e765fe146258d9ed2bbea49b826ad7e1e\", \"traffic_annotation_auditor_unittests\": \"566cb8beb3409bec15ea5ec1c54432c5fe4c7dfe\", \"ui_base_unittests\": \"cfdcecebd79dcaca9463d3666729da690c1dd5d1\", \"ui_touch_selection_unittests\": \"e9860f3ba17386d5e0bbbb26e45143d2fca332f3\", \"unit_tests\": \"d735f5d9ddf36d52479bca5bdf95c2c1858e0559\", \"updater_tests\": \"6f768428161b3adf2eadc521d1b9f773dfcb59b1\", \"url_unittests\": \"013bbcc291e2fb20dae2aca8727258fd964859ba\", \"views_unittests\": \"431ce9c0d238dd262e374ac6725b4ccbee492413\", \"viz_unittests\": \"9cb3b8e6336501b641f1bda8cb67fa1d54f9ded1\", \"vr_common_unittests\": \"a9f51ebad796698fa849eb05a3e06b7cbaeb51b3\", \"vr_pixeltests\": \"3d0c985d4268da59bffa8e7c1a3fcc36a5f18455\", \"weblayer_browsertests\": \"cdfe468c9661a19fd2926271413c5c5e8e4efee2\", \"weblayer_unittests\": \"07c223890a4e91252a8f1a7ff93d70b430ff2c65\", \"wm_unittests\": \"9adb1ca6cd30c4127f3b41b23231a9e055c6eadb\", \"wtf_unittests\": \"adcc2c7dc4bd779f7db81a029813ac52fd5132ce\", \"zlib_unittests\": \"304e5304ebb2551374cb7f429c7a799557921172\", \"zucchini_unittests\": \"efcfe88cceff59ac54654106693c71c8b9336237\"}}, \"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-3-xpf4\"], \"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\": [\"5712-339372b\"], \"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": "1614367265552405",
"status": "COMPLETED",
"status_changed_ts": "1614370921796870",
"tags": [
"build_address:luci.chromium.ci/win-asan/17551",
"builder:win-asan",
"buildset:commit/git/8ff376cf6d21b8ccbf85848b96e1eecd7425f48f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8ff376cf6d21b8ccbf85848b96e1eecd7425f48f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019637013815932288",
"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/8854206951896251392/+/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:51f89b0c44796010",
"user_agent:luci-scheduler"
],
"updated_ts": "1614370921801099",
"url": "https://ci.chromium.org/b/8854206951896251392",
"utcnow_ts": "1614370922762218"
}
}
[20:22:02.825] Invocation finished in 1h1m4.001158731s with status SUCCEEDED