[09:23:34.830] New invocation is queued and will start shortly
[09:23:35.928] Starting the invocation (attempt 1)
[09:23:35.998] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:23:35.998] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:23:35.998] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030093250124647040",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"e832e728cd870e1db351d507327883d8ce35d602\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030093250124647040",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/e832e728cd870e1db351d507327883d8ce35d602",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e832e728cd870e1db351d507327883d8ce35d602",
"gitiles_ref:refs/heads/master"
]
}
[09:23:37.999] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604395416267204",
"id": "8864663187950317248",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e832e728cd870e1db351d507327883d8ce35d602\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604395417754764",
"tags": [
"build_address:luci.chromium.ci/win-asan/15430",
"builder:win-asan",
"buildset:commit/git/e832e728cd870e1db351d507327883d8ce35d602",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e832e728cd870e1db351d507327883d8ce35d602",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030093250124647040",
"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/8864663187950317248/+/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": "1604395417755022",
"url": "https://ci.chromium.org/b/8864663187950317248",
"utcnow_ts": "1604395417987502"
}
}
[09:23:37.999] Task URL: https://ci.chromium.org/b/8864663187950317248
[09:23:37.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:2:0) after 1m0s
[09:23:40.311] Received PubSub notification, asking Buildbucket for the build status
[09:23:40.402] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:38.121] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:2:0)
[09:24:38.121] Timer tick, asking Buildbucket for the build status
[09:24:38.221] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:38.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:4:0) after 1m0s
[09:25:38.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:4:0)
[09:25:38.322] Timer tick, asking Buildbucket for the build status
[09:25:38.452] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:38.452] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:5:0) after 1m0s
[09:26:38.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:5:0)
[09:26:38.480] Timer tick, asking Buildbucket for the build status
[09:26:38.604] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:38.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:6:0) after 1m0s
[09:27:38.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:6:0)
[09:27:38.628] Timer tick, asking Buildbucket for the build status
[09:27:39.215] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:39.215] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:7:0) after 1m0s
[09:28:39.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:7:0)
[09:28:39.276] Timer tick, asking Buildbucket for the build status
[09:28:39.378] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:39.378] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:8:0) after 1m0s
[09:29:39.376] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:8:0)
[09:29:39.376] Timer tick, asking Buildbucket for the build status
[09:29:39.645] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:39.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:9:0) after 1m0s
[09:30:39.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:9:0)
[09:30:39.763] Timer tick, asking Buildbucket for the build status
[09:30:39.826] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:39.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:10:0) after 1m0s
[09:31:39.843] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:10:0)
[09:31:39.843] Timer tick, asking Buildbucket for the build status
[09:31:40.003] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:40.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:11:0) after 1m0s
[09:32:40.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:11:0)
[09:32:40.043] Timer tick, asking Buildbucket for the build status
[09:32:40.115] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:40.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:12:0) after 1m0s
[09:33:40.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:12:0)
[09:33:40.104] Timer tick, asking Buildbucket for the build status
[09:33:40.212] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:40.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:13:0) after 1m0s
[09:34:40.312] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:13:0)
[09:34:40.312] Timer tick, asking Buildbucket for the build status
[09:34:40.436] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:40.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:14:0) after 1m0s
[09:35:40.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:14:0)
[09:35:40.489] Timer tick, asking Buildbucket for the build status
[09:35:40.614] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:40.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:15:0) after 1m0s
[09:36:40.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:15:0)
[09:36:40.634] Timer tick, asking Buildbucket for the build status
[09:36:40.767] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:40.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:16:0) after 1m0s
[09:37:40.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:16:0)
[09:37:40.840] Timer tick, asking Buildbucket for the build status
[09:37:40.913] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:40.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:17:0) after 1m0s
[09:38:41.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:17:0)
[09:38:41.046] Timer tick, asking Buildbucket for the build status
[09:38:41.342] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:41.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:18:0) after 1m0s
[09:39:41.370] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:18:0)
[09:39:41.370] Timer tick, asking Buildbucket for the build status
[09:39:41.534] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:41.534] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:19:0) after 1m0s
[09:40:41.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:19:0)
[09:40:41.597] Timer tick, asking Buildbucket for the build status
[09:40:41.740] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:41.740] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:20:0) after 1m0s
[09:41:41.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:20:0)
[09:41:41.813] Timer tick, asking Buildbucket for the build status
[09:41:42.046] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:42.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:21:0) after 1m0s
[09:42:42.071] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:21:0)
[09:42:42.071] Timer tick, asking Buildbucket for the build status
[09:42:42.197] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:42.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:22:0) after 1m0s
[09:43:42.248] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:22:0)
[09:43:42.248] Timer tick, asking Buildbucket for the build status
[09:43:42.387] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:42.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:23:0) after 1m0s
[09:44:42.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:23:0)
[09:44:42.404] Timer tick, asking Buildbucket for the build status
[09:44:42.597] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:42.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:24:0) after 1m0s
[09:45:42.628] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:24:0)
[09:45:42.628] Timer tick, asking Buildbucket for the build status
[09:45:42.817] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:42.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:25:0) after 1m0s
[09:46:42.848] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:25:0)
[09:46:42.848] Timer tick, asking Buildbucket for the build status
[09:46:42.968] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:42.968] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:26:0) after 1m0s
[09:47:43.058] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:26:0)
[09:47:43.058] Timer tick, asking Buildbucket for the build status
[09:47:43.182] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:43.182] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:27:0) after 1m0s
[09:48:43.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:27:0)
[09:48:43.238] Timer tick, asking Buildbucket for the build status
[09:48:43.670] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:43.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:28:0) after 1m0s
[09:49:43.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:28:0)
[09:49:43.660] Timer tick, asking Buildbucket for the build status
[09:49:43.791] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:43.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:29:0) after 1m0s
[09:50:43.774] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:29:0)
[09:50:43.774] Timer tick, asking Buildbucket for the build status
[09:50:44.329] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:44.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:30:0) after 1m0s
[09:51:44.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:30:0)
[09:51:44.337] Timer tick, asking Buildbucket for the build status
[09:51:44.513] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:44.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:31:0) after 1m0s
[09:52:44.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:31:0)
[09:52:44.593] Timer tick, asking Buildbucket for the build status
[09:52:44.967] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:44.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:32:0) after 1m0s
[09:53:44.954] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:32:0)
[09:53:44.954] Timer tick, asking Buildbucket for the build status
[09:53:45.085] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:45.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:33:0) after 1m0s
[09:54:45.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:33:0)
[09:54:45.156] Timer tick, asking Buildbucket for the build status
[09:54:45.581] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:45.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:34:0) after 1m0s
[09:55:45.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:34:0)
[09:55:45.587] Timer tick, asking Buildbucket for the build status
[09:55:45.807] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:45.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:35:0) after 1m0s
[09:56:45.835] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:35:0)
[09:56:45.835] Timer tick, asking Buildbucket for the build status
[09:56:45.943] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:45.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:36:0) after 1m0s
[09:57:45.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:36:0)
[09:57:45.913] Timer tick, asking Buildbucket for the build status
[09:57:46.008] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:46.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:37:0) after 1m0s
[09:58:46.054] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:37:0)
[09:58:46.054] Timer tick, asking Buildbucket for the build status
[09:58:46.245] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:46.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:38:0) after 1m0s
[09:59:46.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:38:0)
[09:59:46.272] Timer tick, asking Buildbucket for the build status
[09:59:46.347] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:46.347] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:39:0) after 1m0s
[10:00:46.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:39:0)
[10:00:46.410] Timer tick, asking Buildbucket for the build status
[10:00:46.547] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:46.547] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:40:0) after 1m0s
[10:01:46.564] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:40:0)
[10:01:46.564] Timer tick, asking Buildbucket for the build status
[10:01:46.848] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:46.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:41:0) after 1m0s
[10:02:46.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:41:0)
[10:02:46.897] Timer tick, asking Buildbucket for the build status
[10:02:47.039] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:47.039] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:42:0) after 1m0s
[10:03:47.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:42:0)
[10:03:47.095] Timer tick, asking Buildbucket for the build status
[10:03:47.238] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:47.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:43:0) after 1m0s
[10:04:47.292] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:43:0)
[10:04:47.292] Timer tick, asking Buildbucket for the build status
[10:04:47.438] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:47.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:44:0) after 1m0s
[10:05:47.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:44:0)
[10:05:47.462] Timer tick, asking Buildbucket for the build status
[10:05:47.558] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:47.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:45:0) after 1m0s
[10:06:47.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:45:0)
[10:06:47.595] Timer tick, asking Buildbucket for the build status
[10:06:47.722] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:47.722] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:46:0) after 1m0s
[10:07:47.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:46:0)
[10:07:47.887] Timer tick, asking Buildbucket for the build status
[10:07:48.022] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:48.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:47:0) after 1m0s
[10:08:48.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:47:0)
[10:08:48.116] Timer tick, asking Buildbucket for the build status
[10:08:48.493] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:48.493] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:48:0) after 1m0s
[10:09:48.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:48:0)
[10:09:48.592] Timer tick, asking Buildbucket for the build status
[10:09:48.724] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:48.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:49:0) after 1m0s
[10:10:48.851] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:49:0)
[10:10:48.851] Timer tick, asking Buildbucket for the build status
[10:10:48.984] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:48.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:50:0) after 1m0s
[10:11:49.033] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:50:0)
[10:11:49.033] Timer tick, asking Buildbucket for the build status
[10:11:49.148] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:11:49.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:51:0) after 1m0s
[10:12:49.212] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:51:0)
[10:12:49.212] Timer tick, asking Buildbucket for the build status
[10:12:49.731] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:49.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:52:0) after 1m0s
[10:13:49.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:52:0)
[10:13:49.810] Timer tick, asking Buildbucket for the build status
[10:13:49.942] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:13:49.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:53:0) after 1m0s
[10:14:49.974] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:53:0)
[10:14:49.974] Timer tick, asking Buildbucket for the build status
[10:14:50.400] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:14:50.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:54:0) after 1m0s
[10:15:50.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:54:0)
[10:15:50.608] Timer tick, asking Buildbucket for the build status
[10:15:51.088] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:15:51.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:55:0) after 1m0s
[10:16:51.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:55:0)
[10:16:51.116] Timer tick, asking Buildbucket for the build status
[10:16:51.205] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:16:51.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:56:0) after 1m0s
[10:17:51.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:56:0)
[10:17:51.321] Timer tick, asking Buildbucket for the build status
[10:17:51.465] Build 8864663187950317248: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:17:51.465] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030093250124647040:57:0) after 1m0s
[10:18:48.457] Received PubSub notification, asking Buildbucket for the build status
[10:18:48.774] Build 8864663187950317248: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:18:48.774] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604398726329361",
"created_by": "project:chromium",
"created_ts": "1604395416267204",
"id": "8864663187950317248",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e832e728cd870e1db351d507327883d8ce35d602\"}}",
"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\": \"e832e728cd870e1db351d507327883d8ce35d602\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15430, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"ed916ddf61cc0021cebf55fb6901ba71fe9e6a2e\", \"got_dawn_revision\": \"71d2e1d23d49c3616209dbf4776fd720aaa11560\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"e832e728cd870e1db351d507327883d8ce35d602\", \"got_revision_cp\": \"refs/heads/master@{#823481}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"959f41988a18e8511dcde1d646d798de4ea6b59d\", \"got_v8_revision\": \"037ccdfb11c6a626d405939fc582f85d0a8bebb6\", \"got_v8_revision_cp\": \"refs/heads/8.8.218@{#1}\", \"got_webrtc_revision\": \"ee24eb66d23ffea5ef729dc340e1fc4381c72a4a\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32507}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"e832e728cd870e1db351d507327883d8ce35d602\", \"swarm_hashes_refs/heads/master(at){#823481}_without_patch\": {\"absl_hardening_tests\": \"617fbbf61d9a139d1725cf5ceda615cfed492f34\", \"accessibility_unittests\": \"5c1ebd97904d5b4fe577025b08b54caf1266100a\", \"angle_unittests\": \"4773d438f0ac2b7f11b3a820eec42f7dbb081b27\", \"app_shell_unittests\": \"c9246b365ae72ec80f96cde4b42bd59a5f53bb45\", \"aura_unittests\": \"f0ed34f7543e919f9e84240384cb9a94771a27ad\", \"base_unittests\": \"456ebce1564433844f72f5aef14971d46ae1cd79\", \"base_util_unittests\": \"7e1118d09cf8ffee92429d2fe58577a9ba7ff065\", \"blink_common_unittests\": \"4f459a488f23f28531807061d221382cf4c8d9af\", \"blink_fuzzer_unittests\": \"0910d47e16ccf7d6dccfb5e17cddb2bde2cd0e0c\", \"blink_heap_unittests\": \"f76ddc4c9493acd68665b33c2537b1459792ea3a\", \"blink_platform_unittests\": \"6ca9b2db05d79728555bb997027a60b117e32a65\", \"blink_unittests\": \"10d857cabbf0c2564b938c589697c4002b32f513\", \"boringssl_crypto_tests\": \"7ca1910cf57175fdf18453faf960d2b0f8c24b1d\", \"boringssl_ssl_tests\": \"83ef7ac0c282870b360cafba0d20e332f0828344\", \"browser_switcher_bho_unittests\": \"5534328085aeb94c77b533ab709e880e3fdbcb75\", \"browser_tests\": \"0cdcf5d248220b682d01c02d9b29f81a0ae662b5\", \"capture_unittests\": \"e3b0804474e64bc66a0ab179b25b75e4b596e40c\", \"cast_unittests\": \"55cacc083ed3e3de0a913073c36784473ed38529\", \"cc_unittests\": \"b63f8d26343759a3f145320230a81f2e824ed8f6\", \"chrome_app_unittests\": \"6b712f8e6f0e89b0e75241086615ef46656e9143\", \"chrome_cleaner_unittests\": \"e61a99c2c4e0c519b6723014c40b26fe282a03c4\", \"chrome_elf_unittests\": \"382b33dec26e0752b1547d71f7b3fd9cb0af242c\", \"chromedriver_unittests\": \"fefa26798f33c8dcf941ff54c95185c8e23c0f7c\", \"components_browsertests\": \"6b37c82b7f40755c5d82cef1bde2ffb7d6e18422\", \"components_unittests\": \"77efadbf566e7e26c22c4dbbb8531089e46bac61\", \"compositor_unittests\": \"ec2f7fca24b39c17b04ba54103fa7e60b7d439e4\", \"content_browsertests\": \"cc704ec10be3bbee10f90aeb3525339dc7541599\", \"content_unittests\": \"b38e5c842d9aab61606cd146d60f02e3de04c640\", \"courgette_unittests\": \"082892171629e748aac36789f6078af590f9eb43\", \"crashpad_tests\": \"cdbed96aebfcd96585971db139f9de433b9d187e\", \"cronet_tests\": \"b0868b8aa1cd5ce67ef38a1f21e34878a1b423e5\", \"cronet_unittests\": \"befe8870232809f411a40d7f2e75bc522f15444b\", \"crypto_unittests\": \"a0193aedacb1cb83f4f875176ca6a50a5cee4e87\", \"delayloads_unittests\": \"b806b32f581222f0f8ee623f76fb357490655bee\", \"device_unittests\": \"fddd20c41f16041baa922fdbce1bde9795ad0b33\", \"display_unittests\": \"2fd0643c0b320dcd50839fc460e8adbf70350c83\", \"elevation_service_unittests\": \"deac21e5c5a120a9d73efa63a4fa12a01bce74ff\", \"events_unittests\": \"cd09a88dc5c170465a14f8ddd5a0460c14039eab\", \"extensions_browsertests\": \"a99026f71c6be8423ed6f2736a792fd4ba66aa12\", \"extensions_unittests\": \"ea666626e80cedfac22d3778938ba129724097f8\", \"filesystem_service_unittests\": \"eca124efa94e762fcc2e09f53124bf7d3b7adeed\", \"gcm_unit_tests\": \"5d3d46d0f731b150907cf7444154bee8f90e78bb\", \"gcp_unittests\": \"88acba654011c0ee1f0d7eb503053a84091ee572\", \"gfx_unittests\": \"c7ec29a86c4cc81b4a826bf2ad7ad7dbeedc21a6\", \"gin_unittests\": \"61961b7065d7bb822e9a08869728805eb4cbc0fb\", \"google_apis_unittests\": \"2e2e1ad19ec2e01321a43916150227217a3468f4\", \"gpu_unittests\": \"2f839c0c118c1c4c88e209889bdf319a3f538c58\", \"gwp_asan_unittests\": \"e1f8204a0c37becb37d10996192d8e430dde5b50\", \"headless_browsertests\": \"bba12914a0a1ddc460506a7f6864b97e58041e1b\", \"headless_unittests\": \"dda92c9c35688ed823585d679add961e1cd64c9c\", \"install_static_unittests\": \"8fb3caaedc5f9d3c2968200756efaf0bd5c4b959\", \"installer_util_unittests\": \"5100e9054bc825740021a2b09befea5bf65dc7e7\", \"interactive_ui_tests\": \"d8aeee7c3577cf1019e863136c81b077be0cc6af\", \"ipc_tests\": \"27ed7be9cd63342a8639dbba596aa370786de823\", \"jingle_unittests\": \"76976586e1f78da988e633454cb3cf31cd3b1430\", \"latency_unittests\": \"3833450227a91ea5317d2bf69f8f6119cd8d8980\", \"libjingle_xmpp_unittests\": \"9aaa814f870b250afae30eb7811cc77e0fc0872e\", \"liburlpattern_unittests\": \"1908879a95e6fc265401d4054c4bad6956f71bad\", \"media_blink_unittests\": \"68f38aba0b049c210abba006eb52754bad80b56f\", \"media_unittests\": \"b1915e2232701d8104d4df73fed892c17fad1126\", \"message_center_unittests\": \"42f4a123793e1cf228dd3a5ed95ec23c42a83eaf\", \"midi_unittests\": \"00a51969fa947d2e625d05cead23b4900584c0d8\", \"mojo_core_unittests\": \"7e992fe377b95027f1a64841c47a307f547d2f71\", \"mojo_unittests\": \"c5c034a4d5bb21a0c0dd2863354c666d87c0af1a\", \"nacl_loader_unittests\": \"2fe84f2d3405a118a54b9aadcc3c6f1e945ba6d8\", \"native_theme_unittests\": \"132ef11919ebaa82330e9048936e9a829bb1eb61\", \"net_unittests\": \"ba4d9b42f662ff78c48c59db2dac1025159572fa\", \"notification_helper_unittests\": \"3fda617446e707ec80d55ec2a544d954c94e3354\", \"pdf_unittests\": \"9799c3feb17164d0a7f5a31305479fe2cff7850b\", \"perfetto_unittests\": \"e65cbf1ee681352e2e7134a32a9555cb374836b9\", \"ppapi_unittests\": \"6fb01873c96f5e12d362a951b0a635ebb7a42b2b\", \"printing_unittests\": \"db0991da346adba7047384971a685b8e400d03c6\", \"remoting_unittests\": \"2a119de6d379446601c335fe9001c4495f3fa43c\", \"sbox_integration_tests\": \"90e49dd39236c04ca56d966c3dba3bfd9ed8d495\", \"sbox_unittests\": \"64c08558b1dfdc6c53d8804bcdddeea73cff1cc6\", \"sbox_validation_tests\": \"83e070869793e099d81456360e374e1e2de86ae3\", \"service_manager_unittests\": \"2c8c6f2c6601187ad638c66de0024fa9ab78d032\", \"services_unittests\": \"28ceeabd08a39eecfb310bf44ecc64b6beee815c\", \"setup_unittests\": \"f0fc40d7346c7f25d28142868cff65f899a728f5\", \"shell_dialogs_unittests\": \"ebe61eb47736f4591ce22065111b45f4ff24ea66\", \"skia_unittests\": \"53c0af02c12a24cae45b3047c38850c273020147\", \"snapshot_unittests\": \"18be9bf9d342fead17414b748f108384d97e35b0\", \"sql_unittests\": \"00f664de3df2fcf53e82808893c8cf760ea13d7b\", \"storage_unittests\": \"855a711297c7973d684b339f6f977c7b689e47bc\", \"sync_integration_tests\": \"30148cf226451bc43dabdfa66c983b4267ea6021\", \"traffic_annotation_auditor_unittests\": \"a4ec02a16da90e41f1346a886703f3d663f56193\", \"ui_base_unittests\": \"23b7d4112d193e5ba041840a69f568d7abc60552\", \"ui_touch_selection_unittests\": \"c742871ea3c6408d34585cb423ff9ea13a8bca4d\", \"unit_tests\": \"8de8ea99e5ae1c3390f88a6c3c32868899e5cd6a\", \"updater_tests\": \"82f08a1069fb5eaaa7cbfd92db0b18dc40136ec6\", \"url_unittests\": \"078d759ba050adc4cf1acb195b4147fcb7c6981f\", \"views_unittests\": \"c42b5273099c49f1a65880435dcbea0a2fb97624\", \"viz_unittests\": \"8cec6a513d591f88ee2d7bd7ddc3dff92aa83692\", \"vr_common_unittests\": \"6cf3719bd54ba69ad6ae053909a7adf797675558\", \"vr_pixeltests\": \"1156d0b5e2febadb1fb0c946af3f616179838414\", \"weblayer_browsertests\": \"57559d857e03cba3f1b8cbdbe3212f90477077b7\", \"weblayer_unittests\": \"385198a90cea12004d840d4c1ce0a19d18c253d5\", \"wm_unittests\": \"5a4b89f56c117bf7fdb6fea94a7be3057f9885c1\", \"wtf_unittests\": \"089202414773d0b672f1d8de38c268f2a360b2a5\", \"zlib_unittests\": \"8ece2656f7c784e71a9f4209c8f3acbe48647602\", \"zucchini_unittests\": \"2dcb939ee6ca265d052562c4ce660a69a15dfce1\"}}, \"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-5-vrjs\"], \"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\": [\"5614-d862eaa\"], \"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": "1604395418933552",
"status": "COMPLETED",
"status_changed_ts": "1604398726714629",
"tags": [
"build_address:luci.chromium.ci/win-asan/15430",
"builder:win-asan",
"buildset:commit/git/e832e728cd870e1db351d507327883d8ce35d602",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/e832e728cd870e1db351d507327883d8ce35d602",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030093250124647040",
"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/8864663187950317248/+/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:4fa63cc9d8181f10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604398726749225",
"url": "https://ci.chromium.org/b/8864663187950317248",
"utcnow_ts": "1604398728755862"
}
}
[10:18:48.779] Invocation finished in 55m13.966380309s with status SUCCEEDED