[15:16:02.299] New invocation is queued and will start shortly
[15:16:03.440] Starting the invocation (attempt 1)
[15:16:03.477] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:16:03.477] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:16:03.477] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025541227074719888",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"4d025b081ad1a675e8674225d392285f311343a2\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025541227074719888",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/4d025b081ad1a675e8674225d392285f311343a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d025b081ad1a675e8674225d392285f311343a2",
"gitiles_ref:refs/heads/master"
]
}
[15:16:05.062] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608736563608689",
"id": "8860111165036483616",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d025b081ad1a675e8674225d392285f311343a2\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608736564851847",
"tags": [
"build_address:luci.chromium.ci/win-asan/16410",
"builder:win-asan",
"buildset:commit/git/4d025b081ad1a675e8674225d392285f311343a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d025b081ad1a675e8674225d392285f311343a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025541227074719888",
"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/8860111165036483616/+/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": "1608736564852015",
"url": "https://ci.chromium.org/b/8860111165036483616",
"utcnow_ts": "1608736565020435"
}
}
[15:16:05.062] Task URL: https://ci.chromium.org/b/8860111165036483616
[15:16:05.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:2:0) after 1m0s
[15:16:11.255] Received PubSub notification, asking Buildbucket for the build status
[15:16:11.392] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:05.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:2:0)
[15:17:05.105] Timer tick, asking Buildbucket for the build status
[15:17:05.323] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:17:05.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:4:0) after 1m0s
[15:18:05.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:4:0)
[15:18:05.350] Timer tick, asking Buildbucket for the build status
[15:18:05.531] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:18:05.531] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:5:0) after 1m0s
[15:19:05.562] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:5:0)
[15:19:05.562] Timer tick, asking Buildbucket for the build status
[15:19:05.656] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:19:05.656] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:6:0) after 1m0s
[15:20:05.678] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:6:0)
[15:20:05.678] Timer tick, asking Buildbucket for the build status
[15:20:05.764] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:20:05.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:7:0) after 1m0s
[15:21:05.828] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:7:0)
[15:21:05.828] Timer tick, asking Buildbucket for the build status
[15:21:06.012] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:21:06.012] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:8:0) after 1m0s
[15:22:06.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:8:0)
[15:22:06.072] Timer tick, asking Buildbucket for the build status
[15:22:06.222] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:22:06.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:9:0) after 1m0s
[15:23:06.260] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:9:0)
[15:23:06.260] Timer tick, asking Buildbucket for the build status
[15:23:06.322] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:23:06.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:10:0) after 1m0s
[15:24:06.356] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:10:0)
[15:24:06.356] Timer tick, asking Buildbucket for the build status
[15:24:06.486] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:24:06.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:11:0) after 1m0s
[15:25:06.577] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:11:0)
[15:25:06.577] Timer tick, asking Buildbucket for the build status
[15:25:06.727] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:25:06.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:12:0) after 1m0s
[15:26:06.815] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:12:0)
[15:26:06.815] Timer tick, asking Buildbucket for the build status
[15:26:06.916] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:26:06.916] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:13:0) after 1m0s
[15:27:06.953] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:13:0)
[15:27:06.953] Timer tick, asking Buildbucket for the build status
[15:27:07.081] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:27:07.081] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:14:0) after 1m0s
[15:28:07.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:14:0)
[15:28:07.227] Timer tick, asking Buildbucket for the build status
[15:28:07.342] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:28:07.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:15:0) after 1m0s
[15:29:07.360] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:15:0)
[15:29:07.360] Timer tick, asking Buildbucket for the build status
[15:29:07.452] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:29:07.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:16:0) after 1m0s
[15:30:07.504] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:16:0)
[15:30:07.504] Timer tick, asking Buildbucket for the build status
[15:30:07.800] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:30:07.800] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:17:0) after 1m0s
[15:31:07.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:17:0)
[15:31:07.830] Timer tick, asking Buildbucket for the build status
[15:31:08.215] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:08.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:18:0) after 1m0s
[15:32:08.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:18:0)
[15:32:08.238] Timer tick, asking Buildbucket for the build status
[15:32:08.366] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:08.366] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:19:0) after 1m0s
[15:33:08.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:19:0)
[15:33:08.417] Timer tick, asking Buildbucket for the build status
[15:33:08.527] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:08.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:20:0) after 1m0s
[15:34:08.513] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:20:0)
[15:34:08.513] Timer tick, asking Buildbucket for the build status
[15:34:08.647] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:08.647] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:21:0) after 1m0s
[15:35:08.668] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:21:0)
[15:35:08.668] Timer tick, asking Buildbucket for the build status
[15:35:08.856] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:08.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:22:0) after 1m0s
[15:36:08.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:22:0)
[15:36:08.875] Timer tick, asking Buildbucket for the build status
[15:36:09.009] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:09.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:23:0) after 1m0s
[15:37:09.032] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:23:0)
[15:37:09.032] Timer tick, asking Buildbucket for the build status
[15:37:09.134] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:09.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:24:0) after 1m0s
[15:38:09.197] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:24:0)
[15:38:09.197] Timer tick, asking Buildbucket for the build status
[15:38:09.521] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:09.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:25:0) after 1m0s
[15:39:09.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:25:0)
[15:39:09.528] Timer tick, asking Buildbucket for the build status
[15:39:09.597] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:09.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:26:0) after 1m0s
[15:40:09.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:26:0)
[15:40:09.643] Timer tick, asking Buildbucket for the build status
[15:40:09.828] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:09.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:27:0) after 1m0s
[15:41:09.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:27:0)
[15:41:09.847] Timer tick, asking Buildbucket for the build status
[15:41:10.051] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:10.051] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:28:0) after 1m0s
[15:42:10.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:28:0)
[15:42:10.084] Timer tick, asking Buildbucket for the build status
[15:42:10.439] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:10.439] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:29:0) after 1m0s
[15:43:10.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:29:0)
[15:43:10.499] Timer tick, asking Buildbucket for the build status
[15:43:10.583] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:10.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:30:0) after 1m0s
[15:44:10.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:30:0)
[15:44:10.608] Timer tick, asking Buildbucket for the build status
[15:44:10.743] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:10.743] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:31:0) after 1m0s
[15:45:10.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:31:0)
[15:45:10.754] Timer tick, asking Buildbucket for the build status
[15:45:11.993] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:11.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:32:0) after 1m0s
[15:46:12.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:32:0)
[15:46:12.063] Timer tick, asking Buildbucket for the build status
[15:46:12.187] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:12.187] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:33:0) after 1m0s
[15:47:12.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:33:0)
[15:47:12.227] Timer tick, asking Buildbucket for the build status
[15:47:12.337] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:12.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:34:0) after 1m0s
[15:48:12.432] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:34:0)
[15:48:12.432] Timer tick, asking Buildbucket for the build status
[15:48:12.500] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:12.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:35:0) after 1m0s
[15:49:12.568] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:35:0)
[15:49:12.568] Timer tick, asking Buildbucket for the build status
[15:49:12.724] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:12.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:36:0) after 1m0s
[15:50:12.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:36:0)
[15:50:12.779] Timer tick, asking Buildbucket for the build status
[15:50:12.959] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:12.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:37:0) after 1m0s
[15:51:13.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:37:0)
[15:51:13.043] Timer tick, asking Buildbucket for the build status
[15:51:13.175] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:13.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:38:0) after 1m0s
[15:52:14.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:38:0)
[15:52:14.324] Timer tick, asking Buildbucket for the build status
[15:52:14.459] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:14.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:39:0) after 1m0s
[15:53:14.547] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:39:0)
[15:53:14.547] Timer tick, asking Buildbucket for the build status
[15:53:14.733] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:14.733] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:40:0) after 1m0s
[15:54:14.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:40:0)
[15:54:14.796] Timer tick, asking Buildbucket for the build status
[15:54:14.961] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:14.961] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:41:0) after 1m0s
[15:55:15.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:41:0)
[15:55:15.001] Timer tick, asking Buildbucket for the build status
[15:55:15.136] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:15.136] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:42:0) after 1m0s
[15:56:15.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:42:0)
[15:56:15.219] Timer tick, asking Buildbucket for the build status
[15:56:15.331] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:15.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:43:0) after 1m0s
[15:57:15.339] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:43:0)
[15:57:15.339] Timer tick, asking Buildbucket for the build status
[15:57:15.514] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:15.514] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:44:0) after 1m0s
[15:58:15.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:44:0)
[15:58:15.551] Timer tick, asking Buildbucket for the build status
[15:58:15.832] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:15.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:45:0) after 1m0s
[15:59:15.853] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:45:0)
[15:59:15.853] Timer tick, asking Buildbucket for the build status
[15:59:16.508] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:16.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:46:0) after 1m0s
[16:00:16.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:46:0)
[16:00:16.564] Timer tick, asking Buildbucket for the build status
[16:00:16.966] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:16.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:47:0) after 1m0s
[16:01:17.072] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:47:0)
[16:01:17.072] Timer tick, asking Buildbucket for the build status
[16:01:17.222] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:17.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:48:0) after 1m0s
[16:02:17.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:48:0)
[16:02:17.248] Timer tick, asking Buildbucket for the build status
[16:02:17.335] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:17.335] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:49:0) after 1m0s
[16:03:17.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:49:0)
[16:03:17.387] Timer tick, asking Buildbucket for the build status
[16:03:17.475] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:17.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:50:0) after 1m0s
[16:04:17.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:50:0)
[16:04:17.491] Timer tick, asking Buildbucket for the build status
[16:04:17.764] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:17.764] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:51:0) after 1m0s
[16:05:17.876] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:51:0)
[16:05:17.876] Timer tick, asking Buildbucket for the build status
[16:05:17.959] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:17.959] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:52:0) after 1m0s
[16:06:18.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:52:0)
[16:06:18.128] Timer tick, asking Buildbucket for the build status
[16:06:18.235] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:18.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:53:0) after 1m0s
[16:07:18.318] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:53:0)
[16:07:18.318] Timer tick, asking Buildbucket for the build status
[16:07:18.411] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:18.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:54:0) after 1m0s
[16:08:18.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:54:0)
[16:08:18.454] Timer tick, asking Buildbucket for the build status
[16:08:18.575] Build 8860111165036483616: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:18.575] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025541227074719888:55:0) after 1m0s
[16:09:09.764] Received PubSub notification, asking Buildbucket for the build status
[16:09:09.858] Build 8860111165036483616: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:09:09.858] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608739748553229",
"created_by": "project:chromium",
"created_ts": "1608736563608689",
"id": "8860111165036483616",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"4d025b081ad1a675e8674225d392285f311343a2\"}}",
"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\": \"4d025b081ad1a675e8674225d392285f311343a2\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16410, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"d447829c19fd6275b94676aab20e303c224fe126\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"4d025b081ad1a675e8674225d392285f311343a2\", \"got_revision_cp\": \"refs/heads/master@{#839111}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"711cf5ad4a12abe0f6b50058cb2248d7fb80374e\", \"got_v8_revision_cp\": \"refs/heads/8.9.187@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"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\": \"4d025b081ad1a675e8674225d392285f311343a2\", \"swarm_hashes_refs/heads/master(at){#839111}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"9c25cf576cee4b5d6304917b3460cd841b85afa9\", \"angle_unittests\": \"571027a9beb72b2b4a56911580ec4a89ba604851\", \"app_shell_unittests\": \"99744e7e1f5a7ff08ff8d59a423682c380149515\", \"aura_unittests\": \"5462467211dbfcc45d22573f972f389a41809a66\", \"base_unittests\": \"ff09bb7d5f2b63139b7ee1626375a378dd7e50f0\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"eb21fdbc2caef49497b3bdcfccae2bd0f82f4997\", \"blink_fuzzer_unittests\": \"3cd32834dec3868ba3fe96130fc109d69d09babd\", \"blink_heap_unittests\": \"a917099eb97b7dc581ee0860cc40b99881d07626\", \"blink_platform_unittests\": \"3c67a53c93a7eca7a814606f8768bb956eb80d5a\", \"blink_unittests\": \"537355a6002fad66d1c7669e4b0c58859c373aca\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"071fd0f76c89e4fe81bddc4f0ac1f5b77475f1e6\", \"capture_unittests\": \"6baf52caca9e23026e2c5e5251f5d7eda81f48b9\", \"cast_unittests\": \"a2aa57a1cb67e4d9f04bc1edd162f1cbda0f14c1\", \"cc_unittests\": \"bbf9680917ac1a1edffa21ad442c2e90f306da90\", \"chrome_app_unittests\": \"a80c76cc27fe91977a36d210d9b2731278112374\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"a88969f55b5ab3834e9d275c4f5a7d707f88db5d\", \"chromedriver_unittests\": \"20ef7e1248b446f6e474c1c0d1dd176627110010\", \"components_browsertests\": \"3e02c5b4fed8aff78ec596168573f0d0ed95793c\", \"components_unittests\": \"b74fc5b0e56aad15b7a805bc0d0e75fed95120c7\", \"compositor_unittests\": \"2855caffb2ef1f9219d8db02b8384554c650ada6\", \"content_browsertests\": \"8928fde95d87ecad3ff38df60b9071b933f70f66\", \"content_unittests\": \"6acd0420cf4c4efa198385d6aab77038ad560a89\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"2ead7b841b1a0742dee6ef53d49eb06d03f31ca2\", \"device_unittests\": \"e24b10062dc061a21c0a6a6b23d9debf64f6bca9\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"3bf1aaa300d476a7580a9473dcfb056429a6afb2\", \"events_unittests\": \"d62bd5e9f38333e493d0a4c6410ef141c59c2b96\", \"extensions_browsertests\": \"3fa6a108c7022322ba5183464dddae1bd7aa13ff\", \"extensions_unittests\": \"a4f51ef3c5ce909c346e2c1be082aa5022fd720a\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"25aec70dc45cdf8072f609a99acf5503c7a5f276\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"be87c5dc4264fc574b938f678825984e75e90b79\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"e343ad00e4d9d3067bd5bc7dd4b21b1c0c2aa8c4\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"e8d4d8cc757f1df8e7e7ad9b211fb3c8b39b10bd\", \"headless_unittests\": \"c6d5321ec4e1897f817f26bf7c295fa07021de8f\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"e9f54f5127167574027e14faf3db2607152a447e\", \"interactive_ui_tests\": \"5e5787c2693edb83963ec179d7d148de5a3956ef\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"366bca0c611db681c451b5a4bc3477f259ccbe8d\", \"media_unittests\": \"bc62f17666924c0a6f6dd1f837397e085365682a\", \"message_center_unittests\": \"b359f428594bb338c0d7f382fbc84801f9cbebf3\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"48b2fdae4040884d57fb569ec4fda1e1415e56c0\", \"nacl_loader_unittests\": \"3377aecbc57f3dfd4398a5326074e47c8641e075\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"571faf9d98ddf8b84d474f8e563b7ade38f41845\", \"pdf_unittests\": \"70f042d49cbb9e8429c2c29716b06b0cad567ce1\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"528b450ea675e89670cef3ceae84ec4856db5712\", \"printing_unittests\": \"635a60d3faf6915d3d1adc736c9ed0663b584f7d\", \"remoting_unittests\": \"9cc0ee6c35e4d8b1efd17b135c2c0fc53eed9580\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"588dbb3e50fa82ca94246103d290b332a7123df1\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"72cf6c1e86fb36fc141b52c0c1e41150838d8011\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"baddb920bed88f402424aa4c5d0b47b1507bbdad\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"a91a312b9a5dad147f1b49d36efd726ad2d83601\", \"sync_integration_tests\": \"81120045990b581e771d86484cefcbc8d5f4ad00\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"d443564944c0df95efa46cb609a23eca30d61146\", \"ui_touch_selection_unittests\": \"fad905adc771ae651bed83d07cdd7acff764d62e\", \"unit_tests\": \"08c54e3471fa1ca98df6ca97593b717143945706\", \"updater_tests\": \"6f8e91212cbda779190bea93647d7604ab735a40\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"4b2bdcbbc5817096ff9bdff55caeca56a2e667f1\", \"viz_unittests\": \"8af19630b87147afcaeae09e3b9cb4bad90463ce\", \"vr_common_unittests\": \"c88962c4fdc2a015d7217c1018be44ec57101e38\", \"vr_pixeltests\": \"033e9f8646b7ddd7d7f5092ad95f10ca171d58fa\", \"weblayer_browsertests\": \"0575fe165baaa1d87543ba49ff1c1490a29003ab\", \"weblayer_unittests\": \"642bd12d02943bd647577d22b0b39d86c0fd7e1c\", \"wm_unittests\": \"f6a49508c50b3486e3e4022d3eb0d10f84d134d1\", \"wtf_unittests\": \"9fcfc07bf261dbfd4c00be248dd40f6264a10ebe\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"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-6-gpyc\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"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\": [\"5657-0417a3f\"], \"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": "1608736569105421",
"status": "COMPLETED",
"status_changed_ts": "1608739748923162",
"tags": [
"build_address:luci.chromium.ci/win-asan/16410",
"builder:win-asan",
"buildset:commit/git/4d025b081ad1a675e8674225d392285f311343a2",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4d025b081ad1a675e8674225d392285f311343a2",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025541227074719888",
"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/8860111165036483616/+/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:50a8fd6ffbd60910",
"user_agent:luci-scheduler"
],
"updated_ts": "1608739748928950",
"url": "https://ci.chromium.org/b/8860111165036483616",
"utcnow_ts": "1608739749847510"
}
}
[16:09:09.858] Invocation finished in 53m7.576902834s with status SUCCEEDED