[14:17:53.437] New invocation is queued and will start shortly
[14:17:54.555] Starting the invocation (attempt 1)
[14:17:54.592] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:17:54.592] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:17:54.592] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020018470459957872",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a7a7971f93e2b8317b2559d272f7441cb1048c33\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020018470459957872",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a7a7971f93e2b8317b2559d272f7441cb1048c33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7a7971f93e2b8317b2559d272f7441cb1048c33",
"gitiles_ref:refs/heads/master"
]
}
[14:17:55.573] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614003474701160",
"id": "8854588408470280624",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7a7971f93e2b8317b2559d272f7441cb1048c33\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614003475295770",
"tags": [
"build_address:luci.chromium.ci/win-asan/17471",
"builder:win-asan",
"buildset:commit/git/a7a7971f93e2b8317b2559d272f7441cb1048c33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7a7971f93e2b8317b2559d272f7441cb1048c33",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020018470459957872",
"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/8854588408470280624/+/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": "1614003475295922",
"url": "https://ci.chromium.org/b/8854588408470280624",
"utcnow_ts": "1614003475551424"
}
}
[14:17:55.573] Task URL: https://ci.chromium.org/b/8854588408470280624
[14:17:55.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:2:0) after 1m0s
[14:17:58.524] Received PubSub notification, asking Buildbucket for the build status
[14:17:58.799] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:55.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:2:0)
[14:18:55.578] Timer tick, asking Buildbucket for the build status
[14:18:55.798] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:18:55.798] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:4:0) after 1m0s
[14:19:55.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:4:0)
[14:19:55.774] Timer tick, asking Buildbucket for the build status
[14:19:55.894] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:19:55.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:5:0) after 1m0s
[14:20:55.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:5:0)
[14:20:55.946] Timer tick, asking Buildbucket for the build status
[14:20:56.093] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:20:56.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:6:0) after 1m0s
[14:21:56.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:6:0)
[14:21:56.156] Timer tick, asking Buildbucket for the build status
[14:21:56.328] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:21:56.328] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:7:0) after 1m0s
[14:22:56.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:7:0)
[14:22:56.393] Timer tick, asking Buildbucket for the build status
[14:22:56.568] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:22:56.568] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:8:0) after 1m0s
[14:23:56.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:8:0)
[14:23:56.563] Timer tick, asking Buildbucket for the build status
[14:23:56.828] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:23:56.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:9:0) after 1m0s
[14:24:56.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:9:0)
[14:24:56.847] Timer tick, asking Buildbucket for the build status
[14:24:56.923] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:24:56.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:10:0) after 1m0s
[14:25:57.020] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:10:0)
[14:25:57.020] Timer tick, asking Buildbucket for the build status
[14:25:57.095] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:25:57.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:11:0) after 1m0s
[14:26:57.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:11:0)
[14:26:57.212] Timer tick, asking Buildbucket for the build status
[14:26:57.291] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:26:57.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:12:0) after 1m0s
[14:27:57.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:12:0)
[14:27:57.505] Timer tick, asking Buildbucket for the build status
[14:27:57.714] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:27:57.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:13:0) after 1m0s
[14:28:57.783] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:13:0)
[14:28:57.783] Timer tick, asking Buildbucket for the build status
[14:28:57.861] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:28:57.861] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:14:0) after 1m0s
[14:29:57.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:14:0)
[14:29:57.997] Timer tick, asking Buildbucket for the build status
[14:29:58.083] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:29:58.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:15:0) after 1m0s
[14:30:58.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:15:0)
[14:30:58.190] Timer tick, asking Buildbucket for the build status
[14:30:58.401] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:30:58.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:16:0) after 1m0s
[14:31:58.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:16:0)
[14:31:58.424] Timer tick, asking Buildbucket for the build status
[14:31:58.602] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:31:58.602] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:17:0) after 1m0s
[14:32:58.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:17:0)
[14:32:58.613] Timer tick, asking Buildbucket for the build status
[14:32:58.719] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:32:58.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:18:0) after 1m0s
[14:33:58.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:18:0)
[14:33:58.778] Timer tick, asking Buildbucket for the build status
[14:33:59.019] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:33:59.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:19:0) after 1m0s
[14:34:59.074] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:19:0)
[14:34:59.074] Timer tick, asking Buildbucket for the build status
[14:34:59.149] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:34:59.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:20:0) after 1m0s
[14:35:59.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:20:0)
[14:35:59.204] Timer tick, asking Buildbucket for the build status
[14:35:59.451] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:35:59.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:21:0) after 1m0s
[14:36:59.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:21:0)
[14:36:59.624] Timer tick, asking Buildbucket for the build status
[14:36:59.698] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:36:59.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:22:0) after 1m0s
[14:37:59.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:22:0)
[14:37:59.713] Timer tick, asking Buildbucket for the build status
[14:38:00.186] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:38:00.186] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:23:0) after 1m0s
[14:39:00.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:23:0)
[14:39:00.214] Timer tick, asking Buildbucket for the build status
[14:39:00.312] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:39:00.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:24:0) after 1m0s
[14:40:00.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:24:0)
[14:40:00.757] Timer tick, asking Buildbucket for the build status
[14:40:00.838] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:40:00.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:25:0) after 1m0s
[14:41:00.857] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:25:0)
[14:41:00.857] Timer tick, asking Buildbucket for the build status
[14:41:00.934] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:41:00.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:26:0) after 1m0s
[14:42:01.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:26:0)
[14:42:01.453] Timer tick, asking Buildbucket for the build status
[14:42:01.647] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:42:01.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:27:0) after 1m0s
[14:43:01.846] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:27:0)
[14:43:01.846] Timer tick, asking Buildbucket for the build status
[14:43:02.298] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:43:02.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:28:0) after 1m0s
[14:44:02.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:28:0)
[14:44:02.337] Timer tick, asking Buildbucket for the build status
[14:44:02.438] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:44:02.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:29:0) after 1m0s
[14:45:02.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:29:0)
[14:45:02.430] Timer tick, asking Buildbucket for the build status
[14:45:02.545] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:45:02.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:30:0) after 1m0s
[14:46:02.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:30:0)
[14:46:02.548] Timer tick, asking Buildbucket for the build status
[14:46:02.639] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:46:02.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:31:0) after 1m0s
[14:47:02.718] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:31:0)
[14:47:02.718] Timer tick, asking Buildbucket for the build status
[14:47:02.844] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:47:02.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:32:0) after 1m0s
[14:48:02.931] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:32:0)
[14:48:02.931] Timer tick, asking Buildbucket for the build status
[14:48:03.026] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:48:03.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:33:0) after 1m0s
[14:49:03.117] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:33:0)
[14:49:03.117] Timer tick, asking Buildbucket for the build status
[14:49:03.224] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:49:03.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:34:0) after 1m0s
[14:50:03.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:34:0)
[14:50:03.303] Timer tick, asking Buildbucket for the build status
[14:50:03.897] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:50:03.897] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:35:0) after 1m0s
[14:51:03.999] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:35:0)
[14:51:03.999] Timer tick, asking Buildbucket for the build status
[14:51:04.156] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:51:04.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:36:0) after 1m0s
[14:52:04.184] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:36:0)
[14:52:04.184] Timer tick, asking Buildbucket for the build status
[14:52:04.311] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:52:04.311] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:37:0) after 1m0s
[14:53:04.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:37:0)
[14:53:04.508] Timer tick, asking Buildbucket for the build status
[14:53:04.619] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:53:04.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:38:0) after 1m0s
[14:54:04.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:38:0)
[14:54:04.962] Timer tick, asking Buildbucket for the build status
[14:54:05.122] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:54:05.122] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:39:0) after 1m0s
[14:55:05.141] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:39:0)
[14:55:05.141] Timer tick, asking Buildbucket for the build status
[14:55:05.625] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:55:05.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:40:0) after 1m0s
[14:56:05.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:40:0)
[14:56:05.727] Timer tick, asking Buildbucket for the build status
[14:56:05.850] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:56:05.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:41:0) after 1m0s
[14:57:05.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:41:0)
[14:57:05.890] Timer tick, asking Buildbucket for the build status
[14:57:06.148] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:57:06.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:42:0) after 1m0s
[14:58:06.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:42:0)
[14:58:06.245] Timer tick, asking Buildbucket for the build status
[14:58:06.414] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:58:06.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:43:0) after 1m0s
[14:59:06.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:43:0)
[14:59:06.399] Timer tick, asking Buildbucket for the build status
[14:59:06.502] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[14:59:06.502] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:44:0) after 1m0s
[15:00:06.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:44:0)
[15:00:06.707] Timer tick, asking Buildbucket for the build status
[15:00:06.983] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:00:06.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:45:0) after 1m0s
[15:01:08.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:45:0)
[15:01:08.143] Timer tick, asking Buildbucket for the build status
[15:01:08.241] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:01:08.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:46:0) after 1m0s
[15:02:08.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:46:0)
[15:02:08.337] Timer tick, asking Buildbucket for the build status
[15:02:08.599] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:02:08.599] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:47:0) after 1m0s
[15:03:08.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:47:0)
[15:03:08.674] Timer tick, asking Buildbucket for the build status
[15:03:08.791] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:03:08.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:48:0) after 1m0s
[15:04:09.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:48:0)
[15:04:09.055] Timer tick, asking Buildbucket for the build status
[15:04:09.212] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:04:09.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:49:0) after 1m0s
[15:05:09.229] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:49:0)
[15:05:09.229] Timer tick, asking Buildbucket for the build status
[15:05:09.336] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:05:09.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:50:0) after 1m0s
[15:06:09.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:50:0)
[15:06:09.415] Timer tick, asking Buildbucket for the build status
[15:06:09.638] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:06:09.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:51:0) after 1m0s
[15:07:09.680] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:51:0)
[15:07:09.680] Timer tick, asking Buildbucket for the build status
[15:07:09.759] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:07:09.759] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:52:0) after 1m0s
[15:08:09.795] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:52:0)
[15:08:09.795] Timer tick, asking Buildbucket for the build status
[15:08:09.920] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:08:09.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:53:0) after 1m0s
[15:09:10.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:53:0)
[15:09:10.270] Timer tick, asking Buildbucket for the build status
[15:09:10.447] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:09:10.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:54:0) after 1m0s
[15:10:10.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:54:0)
[15:10:10.608] Timer tick, asking Buildbucket for the build status
[15:10:10.698] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:10:10.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:55:0) after 1m0s
[15:11:10.745] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:55:0)
[15:11:10.745] Timer tick, asking Buildbucket for the build status
[15:11:10.834] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:11:10.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:56:0) after 1m0s
[15:12:10.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:56:0)
[15:12:10.879] Timer tick, asking Buildbucket for the build status
[15:12:10.964] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:12:10.964] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:57:0) after 1m0s
[15:13:11.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:57:0)
[15:13:11.085] Timer tick, asking Buildbucket for the build status
[15:13:11.355] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:13:11.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:58:0) after 1m0s
[15:14:11.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:58:0)
[15:14:11.504] Timer tick, asking Buildbucket for the build status
[15:14:11.795] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:14:11.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:59:0) after 1m0s
[15:15:11.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:59:0)
[15:15:11.924] Timer tick, asking Buildbucket for the build status
[15:15:12.038] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:15:12.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:60:0) after 1m0s
[15:16:12.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:60:0)
[15:16:12.134] Timer tick, asking Buildbucket for the build status
[15:16:12.257] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:16:12.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:61:0) after 1m0s
[15:17:12.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:61:0)
[15:17:12.280] Timer tick, asking Buildbucket for the build status
[15:17:12.357] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:12.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:62:0) after 1m0s
[15:18:12.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:62:0)
[15:18:12.489] Timer tick, asking Buildbucket for the build status
[15:18:12.624] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:12.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:63:0) after 1m0s
[15:19:12.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:63:0)
[15:19:12.749] Timer tick, asking Buildbucket for the build status
[15:19:12.856] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:12.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:64:0) after 1m0s
[15:20:12.856] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:64:0)
[15:20:12.856] Timer tick, asking Buildbucket for the build status
[15:20:12.953] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:12.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:65:0) after 1m0s
[15:21:12.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:65:0)
[15:21:12.986] Timer tick, asking Buildbucket for the build status
[15:21:13.077] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:13.077] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:66:0) after 1m0s
[15:22:13.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:66:0)
[15:22:13.087] Timer tick, asking Buildbucket for the build status
[15:22:13.487] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:13.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:67:0) after 1m0s
[15:23:13.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:67:0)
[15:23:13.546] Timer tick, asking Buildbucket for the build status
[15:23:13.637] Build 8854588408470280624: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:13.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020018470459957872:68:0) after 1m0s
[15:23:46.910] Received PubSub notification, asking Buildbucket for the build status
[15:23:46.998] Build 8854588408470280624: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[15:23:46.999] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614007425868315",
"created_by": "project:chromium",
"created_ts": "1614003474701160",
"id": "8854588408470280624",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a7a7971f93e2b8317b2559d272f7441cb1048c33\"}}",
"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\": \"a7a7971f93e2b8317b2559d272f7441cb1048c33\", \"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\": 17471, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"196de52a3670581eee89544cea89bcb57da00751\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"a7a7971f93e2b8317b2559d272f7441cb1048c33\", \"got_revision_cp\": \"refs/heads/master@{#856262}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"d5e3902e5ab63cc50c95c1552ba8571d003613cd\", \"got_v8_revision_cp\": \"refs/heads/9.0.232@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"a7a7971f93e2b8317b2559d272f7441cb1048c33\", \"swarm_hashes_refs/heads/master(at){#856262}_without_patch\": {\"absl_hardening_tests\": \"77719e81557e70364610618acd520b379aadaa96\", \"accessibility_unittests\": \"a3f692b51f4347927ea073796f39040041bc6efa\", \"angle_unittests\": \"f77cde756f6f9fba6065a4187a536d6728065705\", \"app_shell_unittests\": \"529fc8398f5a51153e7c281f650f6514c59b1465\", \"aura_unittests\": \"2528fb0860b9a271577ea0ef68126577129e62d2\", \"base_unittests\": \"b14e920ae8cc62f8449dbc5d735c353deb2a1dc1\", \"base_util_unittests\": \"a889c8178aad53fc24a7a3c0fbabb93d6a22c2db\", \"blink_common_unittests\": \"fd484d23bdea9e79f3c1d35214cc538ce95a2f6a\", \"blink_fuzzer_unittests\": \"e1fa820bd01eaa0c561b19390f985eb0ae55f182\", \"blink_heap_unittests\": \"a3a7dada89729c27bb9f0ff27a731135deb2af14\", \"blink_platform_unittests\": \"52d57e895275eb36e98a0e0db5f617423e115da2\", \"blink_unittests\": \"f11c349cf476a769d223db416e44c2e63b3b013d\", \"boringssl_crypto_tests\": \"cd85dfaadb1e9288ae2d117c7010942d121712f9\", \"boringssl_ssl_tests\": \"36523e11dec874cd24d334a79a54651be462281f\", \"browser_switcher_bho_unittests\": \"d44d7547db1f6c0af175a286ea9ef8b11a46e781\", \"browser_tests\": \"1b22a70f3a422ff1ef522d2762b43be193cfb895\", \"capture_unittests\": \"0d80a877f18f3bf91f13ce7d95bb4c1dc006337c\", \"cast_unittests\": \"7e7690be1d2777b1e713712fc2430fb587b32b06\", \"cc_unittests\": \"1e7c34743f9d74c39f6beb56ac3e1a4b74b2e7ef\", \"chrome_app_unittests\": \"aab31ef8418e39fb8968d794ab6ed34f7668054a\", \"chrome_cleaner_unittests\": \"5d1ad3eb62f796f749491a2225a05917b19f8dcc\", \"chrome_elf_unittests\": \"58e7043b648540e7034809558e274a6f00827ee6\", \"chromedriver_unittests\": \"df9bb3fddc9782f76de4d6b5da23fa4223c01a03\", \"components_browsertests\": \"a4085a7507a178680cab947073c46e4483159238\", \"components_unittests\": \"2d967270325084e9e30b79d5a3998ef72aca2b52\", \"compositor_unittests\": \"7bd54260ef59e17d8720f85fe469c90d31040681\", \"content_browsertests\": \"83ef76016e558ca07fdd1d3cea17b2dc4599b532\", \"content_unittests\": \"ba8f5700b42b409952254f5546522838ed8a2869\", \"courgette_unittests\": \"e83224d4c7b48ca8773755209def5a8f18928741\", \"crashpad_tests\": \"31c1ec62a70052216d201501e589288e44cecb5e\", \"cronet_tests\": \"834fcd29ccf6625284c83ab4ae914bde2a26c89c\", \"cronet_unittests\": \"fe274cfc79565d5cd15043162b128b0f773c64b8\", \"crypto_unittests\": \"95eda521f8279fe2509d3eda600cfc7e34e052da\", \"delayloads_unittests\": \"9846fc97d8003a4cc68233dc8cf327894c16f89a\", \"device_unittests\": \"3dba7b6b338c865a8f0aea31b18fd90ad00fe9df\", \"display_unittests\": \"99eaa871d54178b3a177cad7ed6b6daa716dd56f\", \"elevation_service_unittests\": \"a7f0a03b287b90111749a19db4f63fed372328ca\", \"events_unittests\": \"ca8e10344daeb84d242aca7e2984a8bde19a6f85\", \"extensions_browsertests\": \"01da75c10de46ca20eefc39a47a718dedb0ccfb1\", \"extensions_unittests\": \"218cd369b11bec7ce8d6f4d03c420c2ee40e9be3\", \"filesystem_service_unittests\": \"3072f7b10ba6129d8a60035e8476466e7a8918f7\", \"gcm_unit_tests\": \"4e664c850f2b60d0c3ed22ca673740474cfd1b20\", \"gcp_unittests\": \"731b1dad8dcf43f6526a6ba971357e4bd5b81eff\", \"gfx_unittests\": \"a8f4b120b54e3218c13a8dd0aed41bfcf67e7673\", \"gin_unittests\": \"85a26a708660079de49841a5fcb543adfe1591b6\", \"google_apis_unittests\": \"a79fdc0eab261404a59b9d8daa32f6f9b47e6bbb\", \"gpu_unittests\": \"9dcae46258310059e940e068f3e66d3e0b33bd87\", \"gwp_asan_unittests\": \"d97c1e949a83149acbf120c84cc12d365c0d1a62\", \"headless_browsertests\": \"b5e171579e6eccc1c0232e69ef464c071b578910\", \"headless_unittests\": \"72031f985833a887561cb6cdffaa419f5d4cb393\", \"install_static_unittests\": \"d547ea9bed207418b6d2ed3d993c156396b9b461\", \"installer_util_unittests\": \"84a58b6a6e132ba14ab4154407b5109f48b0703f\", \"interactive_ui_tests\": \"6fbe703a95f15457ad8292d0ccc47e9d9e0c2de2\", \"ipc_tests\": \"5ca3fc2721dabd5009e9437de8be84a6e961dbd9\", \"jingle_unittests\": \"646e980a1ce827b071b6bdfe850002f3fa089ba9\", \"latency_unittests\": \"c2320da5a897584af0d193eed4861b4d4e1deafb\", \"libjingle_xmpp_unittests\": \"74f5f0c77945a987a8afc5f90445dd2de13557a5\", \"liburlpattern_unittests\": \"dea5e569d195bfca3aacd38fb465106c8e61a832\", \"media_blink_unittests\": \"22d7437584719bab5e19032133ae8d75cb33c893\", \"media_unittests\": \"866b0f1ce2f4eaa84b45d55a734e31a46ac6d97e\", \"message_center_unittests\": \"8896ef21c4c74d16f9e07e79ece7bbfd480f46e6\", \"midi_unittests\": \"4ba68bc4e1616243b7f8e57ff5be826a83ebc549\", \"mojo_core_unittests\": \"f37482c37fbd0d20b17c6715adf7fe84cc63319a\", \"mojo_unittests\": \"aea23a1e14f2646323a30d5dc1bd8d022730b89a\", \"nacl_loader_unittests\": \"09708185bc10d1b339f63270d5f79320a09d64cd\", \"native_theme_unittests\": \"41570ef2edd88b4de175b034a011bbc7e2b10305\", \"net_unittests\": \"418b644e5a6de039ce0fda2b31de13a1e6bad489\", \"notification_helper_unittests\": \"facb9489dc76c15d53f37efdbeb4583e27e6356a\", \"pdf_unittests\": \"fe3f73bba74fdd96eac0a8aff400deb3977d9275\", \"perfetto_unittests\": \"475f77640758d3135555e3a0c61bb0503336288f\", \"ppapi_unittests\": \"43d60726d8430ab56caf6f7011bc9584f80b71b4\", \"printing_unittests\": \"791a327559ec7af562918e5f6113d5f6b45c9090\", \"remoting_unittests\": \"aaa7fe149c84ee3dcc97817f96911b71bbfac971\", \"sbox_integration_tests\": \"e7d5fb61bf58b901661697eb9727a5efc3c73f69\", \"sbox_unittests\": \"7ee5d06f464e7cf857a4a8332955f72f57b18645\", \"sbox_validation_tests\": \"53945b8dddc321031058b3ed964bdd4ee4f1a105\", \"service_manager_unittests\": \"3a6d537a278eede72ead2e0503ff6c7aaf479304\", \"services_unittests\": \"c3bbd7c05e87ee59e0fd8498386b6428b80d396e\", \"setup_unittests\": \"6cba07bba2182e271398fcc321d69999ad64b667\", \"shell_dialogs_unittests\": \"5aa95a715d303d1dca29b3c432ead54bb4cea5e3\", \"skia_unittests\": \"8660697e2243431edd4033c21048c7f70e7286c5\", \"snapshot_unittests\": \"9d7a29f39e5bbd81c9d885d7b05f0a0be3ef2670\", \"sql_unittests\": \"7840df688004a0b2ee84fd7883ecc08e3eb29b7c\", \"storage_unittests\": \"010e45c63b7f57121f26c79c7497626fcff4f74b\", \"sync_integration_tests\": \"0a863c3f516f6dda917c1055ed315372e724133c\", \"traffic_annotation_auditor_unittests\": \"25be729539da5bcb3b88501757dc65a921f2d273\", \"ui_base_unittests\": \"f7639fea5407046c53ee3e000ce69fa917d92fe7\", \"ui_touch_selection_unittests\": \"7562d251ce6975491afe5ae89417cea7c276f5fa\", \"unit_tests\": \"7d7002b9d50b5b062dea52867756139192fd1ce8\", \"updater_tests\": \"7f3d49cd969fc28a7add6c3da771f60baa1bd9c2\", \"url_unittests\": \"50b87ecbc93e3e3ec558164f743f729ce018c462\", \"views_unittests\": \"3105a385ab5303d34bd995b1b10025cf01e8cecb\", \"viz_unittests\": \"4e51acf72fa515c0240a03c53cb04f1b67b6894f\", \"vr_common_unittests\": \"3e2496dfe9f8f41322fffe898acbac0d0205dd83\", \"vr_pixeltests\": \"9c42f1592507b319d999a93eb3abe9b84f4c5c4f\", \"weblayer_browsertests\": \"26a01bb28663e4ce598015c6fa86403d5edb7ed8\", \"weblayer_unittests\": \"743843c8a9f9c241c0fe34cfcf665b28278fbcf7\", \"wm_unittests\": \"d492509c6f30b9816e9bd4ac62b7477dd34fc12e\", \"wtf_unittests\": \"d5794cae12babdd69bd019e2ab05d0295675f862\", \"zlib_unittests\": \"7a7235d8249466aec4772ff1839cae8dd452f2bc\", \"zucchini_unittests\": \"a5fcc385f8d184f845cbf4d2e6d058b7bdbae044\"}}, \"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\": [\"5706-397711c\"], \"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": "1614003477826380",
"status": "COMPLETED",
"status_changed_ts": "1614007426297287",
"tags": [
"build_address:luci.chromium.ci/win-asan/17471",
"builder:win-asan",
"buildset:commit/git/a7a7971f93e2b8317b2559d272f7441cb1048c33",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a7a7971f93e2b8317b2559d272f7441cb1048c33",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020018470459957872",
"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/8854588408470280624/+/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:51e2ec1edb3e1610",
"user_agent:luci-scheduler"
],
"updated_ts": "1614007426337140",
"url": "https://ci.chromium.org/b/8854588408470280624",
"utcnow_ts": "1614007426985814"
}
}
[15:23:47.001] Invocation finished in 1h5m53.58121811s with status SUCCEEDED