[04:19:49.238] New invocation is queued and will start shortly
[04:19:50.458] Starting the invocation (attempt 1)
[04:19:50.518] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:19:50.518] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:19:50.518] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027485049013481792",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"d249b869e21325cf92d4d9f45486be807b2c20ee\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027485049013481792",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/d249b869e21325cf92d4d9f45486be807b2c20ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d249b869e21325cf92d4d9f45486be807b2c20ee",
"gitiles_ref:refs/heads/master"
]
}
[04:19:52.217] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1606882790705064",
"id": "8862054986811666720",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d249b869e21325cf92d4d9f45486be807b2c20ee\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1606882791924159",
"tags": [
"build_address:luci.chromium.ci/win-asan/15978",
"builder:win-asan",
"buildset:commit/git/d249b869e21325cf92d4d9f45486be807b2c20ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d249b869e21325cf92d4d9f45486be807b2c20ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027485049013481792",
"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/8862054986811666720/+/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": "1606882791924254",
"url": "https://ci.chromium.org/b/8862054986811666720",
"utcnow_ts": "1606882792205553"
}
}
[04:19:52.217] Task URL: https://ci.chromium.org/b/8862054986811666720
[04:19:52.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:2:0) after 1m0s
[04:19:55.548] Received PubSub notification, asking Buildbucket for the build status
[04:19:55.638] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:52.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:2:0)
[04:20:52.287] Timer tick, asking Buildbucket for the build status
[04:20:52.982] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:52.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:4:0) after 1m0s
[04:21:52.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:4:0)
[04:21:52.985] Timer tick, asking Buildbucket for the build status
[04:21:53.166] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:53.166] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:5:0) after 1m0s
[04:22:53.188] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:5:0)
[04:22:53.188] Timer tick, asking Buildbucket for the build status
[04:22:53.323] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:53.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:6:0) after 1m0s
[04:23:53.307] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:6:0)
[04:23:53.307] Timer tick, asking Buildbucket for the build status
[04:23:53.570] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:53.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:7:0) after 1m0s
[04:24:53.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:7:0)
[04:24:53.634] Timer tick, asking Buildbucket for the build status
[04:24:53.730] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:53.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:8:0) after 1m0s
[04:25:53.723] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:8:0)
[04:25:53.723] Timer tick, asking Buildbucket for the build status
[04:25:53.819] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:53.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:9:0) after 1m0s
[04:26:53.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:9:0)
[04:26:53.839] Timer tick, asking Buildbucket for the build status
[04:26:53.946] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:53.946] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:10:0) after 1m0s
[04:27:53.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:10:0)
[04:27:53.973] Timer tick, asking Buildbucket for the build status
[04:27:54.197] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:54.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:11:0) after 1m0s
[04:28:54.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:11:0)
[04:28:54.295] Timer tick, asking Buildbucket for the build status
[04:28:54.467] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:54.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:12:0) after 1m0s
[04:29:54.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:12:0)
[04:29:54.528] Timer tick, asking Buildbucket for the build status
[04:29:54.653] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:54.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:13:0) after 1m0s
[04:30:54.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:13:0)
[04:30:54.775] Timer tick, asking Buildbucket for the build status
[04:30:54.845] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:54.845] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:14:0) after 1m0s
[04:31:54.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:14:0)
[04:31:54.957] Timer tick, asking Buildbucket for the build status
[04:31:55.031] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:55.031] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:15:0) after 1m0s
[04:32:55.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:15:0)
[04:32:55.053] Timer tick, asking Buildbucket for the build status
[04:32:55.126] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:55.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:16:0) after 1m0s
[04:33:55.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:16:0)
[04:33:55.195] Timer tick, asking Buildbucket for the build status
[04:33:55.372] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:55.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:17:0) after 1m0s
[04:34:55.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:17:0)
[04:34:55.412] Timer tick, asking Buildbucket for the build status
[04:34:55.557] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:55.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:18:0) after 1m0s
[04:35:55.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:18:0)
[04:35:55.594] Timer tick, asking Buildbucket for the build status
[04:35:55.683] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:55.683] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:19:0) after 1m0s
[04:36:55.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:19:0)
[04:36:55.708] Timer tick, asking Buildbucket for the build status
[04:36:56.626] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:56.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:20:0) after 1m0s
[04:37:56.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:20:0)
[04:37:56.661] Timer tick, asking Buildbucket for the build status
[04:37:56.730] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:56.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:21:0) after 1m0s
[04:38:56.758] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:21:0)
[04:38:56.758] Timer tick, asking Buildbucket for the build status
[04:38:59.156] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:59.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:22:0) after 1m0s
[04:39:59.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:22:0)
[04:39:59.245] Timer tick, asking Buildbucket for the build status
[04:39:59.372] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:59.372] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:23:0) after 1m0s
[04:40:59.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:23:0)
[04:40:59.451] Timer tick, asking Buildbucket for the build status
[04:40:59.508] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:59.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:24:0) after 1m0s
[04:41:59.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:24:0)
[04:41:59.598] Timer tick, asking Buildbucket for the build status
[04:41:59.668] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:59.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:25:0) after 1m0s
[04:42:59.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:25:0)
[04:42:59.708] Timer tick, asking Buildbucket for the build status
[04:42:59.844] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:59.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:26:0) after 1m0s
[04:43:59.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:26:0)
[04:43:59.904] Timer tick, asking Buildbucket for the build status
[04:44:00.216] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:00.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:27:0) after 1m0s
[04:45:01.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:27:0)
[04:45:01.467] Timer tick, asking Buildbucket for the build status
[04:45:01.699] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:01.699] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:28:0) after 1m0s
[04:46:01.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:28:0)
[04:46:01.744] Timer tick, asking Buildbucket for the build status
[04:46:01.914] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:01.914] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:29:0) after 1m0s
[04:47:01.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:29:0)
[04:47:01.998] Timer tick, asking Buildbucket for the build status
[04:47:02.792] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:02.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:30:0) after 1m0s
[04:48:02.878] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:30:0)
[04:48:02.878] Timer tick, asking Buildbucket for the build status
[04:48:02.982] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:02.982] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:31:0) after 1m0s
[04:49:03.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:31:0)
[04:49:03.204] Timer tick, asking Buildbucket for the build status
[04:49:03.840] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:03.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:32:0) after 1m0s
[04:50:03.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:32:0)
[04:50:03.877] Timer tick, asking Buildbucket for the build status
[04:50:05.691] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:05.691] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:33:0) after 1m0s
[04:51:05.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:33:0)
[04:51:05.755] Timer tick, asking Buildbucket for the build status
[04:51:05.878] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:05.878] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:34:0) after 1m0s
[04:52:05.915] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:34:0)
[04:52:05.915] Timer tick, asking Buildbucket for the build status
[04:52:06.184] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:06.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:35:0) after 1m0s
[04:53:06.578] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:35:0)
[04:53:06.578] Timer tick, asking Buildbucket for the build status
[04:53:06.832] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:06.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:36:0) after 1m0s
[04:54:06.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:36:0)
[04:54:06.930] Timer tick, asking Buildbucket for the build status
[04:54:07.054] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:07.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:37:0) after 1m0s
[04:55:07.196] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:37:0)
[04:55:07.196] Timer tick, asking Buildbucket for the build status
[04:55:08.487] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:08.489] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:38:0) after 1m0s
[04:56:08.517] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:38:0)
[04:56:08.517] Timer tick, asking Buildbucket for the build status
[04:56:08.620] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:08.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:39:0) after 1m0s
[04:57:08.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:39:0)
[04:57:08.674] Timer tick, asking Buildbucket for the build status
[04:57:08.789] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:08.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:40:0) after 1m0s
[04:58:08.838] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:40:0)
[04:58:08.838] Timer tick, asking Buildbucket for the build status
[04:58:09.066] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:09.066] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:41:0) after 1m0s
[04:59:09.097] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:41:0)
[04:59:09.097] Timer tick, asking Buildbucket for the build status
[04:59:09.351] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:09.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:42:0) after 1m0s
[05:00:09.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:42:0)
[05:00:09.393] Timer tick, asking Buildbucket for the build status
[05:00:09.620] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:09.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:43:0) after 1m0s
[05:01:09.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:43:0)
[05:01:09.681] Timer tick, asking Buildbucket for the build status
[05:01:20.495] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:20.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:44:0) after 1m0s
[05:02:20.576] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:44:0)
[05:02:20.576] Timer tick, asking Buildbucket for the build status
[05:02:20.923] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:20.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:45:0) after 1m0s
[05:03:20.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:45:0)
[05:03:20.993] Timer tick, asking Buildbucket for the build status
[05:03:21.461] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:21.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:46:0) after 1m0s
[05:04:21.613] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:46:0)
[05:04:21.613] Timer tick, asking Buildbucket for the build status
[05:04:21.711] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:21.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:47:0) after 1m0s
[05:05:21.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:47:0)
[05:05:21.824] Timer tick, asking Buildbucket for the build status
[05:05:22.043] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:22.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:48:0) after 1m0s
[05:06:22.174] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:48:0)
[05:06:22.174] Timer tick, asking Buildbucket for the build status
[05:06:22.239] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:22.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:49:0) after 1m0s
[05:07:22.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:49:0)
[05:07:22.272] Timer tick, asking Buildbucket for the build status
[05:07:22.404] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:22.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:50:0) after 1m0s
[05:08:22.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:50:0)
[05:08:22.419] Timer tick, asking Buildbucket for the build status
[05:08:22.633] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:22.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:51:0) after 1m0s
[05:09:22.659] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:51:0)
[05:09:22.659] Timer tick, asking Buildbucket for the build status
[05:09:23.243] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:23.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:52:0) after 1m0s
[05:10:23.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:52:0)
[05:10:23.321] Timer tick, asking Buildbucket for the build status
[05:10:23.458] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:23.458] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:53:0) after 1m0s
[05:11:23.447] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:53:0)
[05:11:23.447] Timer tick, asking Buildbucket for the build status
[05:11:23.757] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:23.757] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:54:0) after 1m0s
[05:12:23.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:54:0)
[05:12:23.881] Timer tick, asking Buildbucket for the build status
[05:12:24.057] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:24.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:55:0) after 1m0s
[05:13:24.535] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:55:0)
[05:13:24.535] Timer tick, asking Buildbucket for the build status
[05:13:24.724] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:24.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:56:0) after 1m0s
[05:14:24.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:56:0)
[05:14:24.748] Timer tick, asking Buildbucket for the build status
[05:14:24.826] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:24.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:57:0) after 1m0s
[05:15:24.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:57:0)
[05:15:24.962] Timer tick, asking Buildbucket for the build status
[05:15:25.086] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:25.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:58:0) after 1m0s
[05:16:25.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:58:0)
[05:16:25.126] Timer tick, asking Buildbucket for the build status
[05:16:25.235] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:25.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:59:0) after 1m0s
[05:17:25.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:59:0)
[05:17:25.314] Timer tick, asking Buildbucket for the build status
[05:17:25.388] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:25.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:60:0) after 1m0s
[05:18:25.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:60:0)
[05:18:25.523] Timer tick, asking Buildbucket for the build status
[05:18:25.626] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:25.626] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:61:0) after 1m0s
[05:19:25.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:61:0)
[05:19:25.728] Timer tick, asking Buildbucket for the build status
[05:19:25.895] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:25.895] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:62:0) after 1m0s
[05:20:25.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:62:0)
[05:20:25.950] Timer tick, asking Buildbucket for the build status
[05:20:26.158] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:26.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:63:0) after 1m0s
[05:21:26.224] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:63:0)
[05:21:26.224] Timer tick, asking Buildbucket for the build status
[05:21:26.577] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:26.577] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:64:0) after 1m0s
[05:22:26.619] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:64:0)
[05:22:26.619] Timer tick, asking Buildbucket for the build status
[05:22:26.932] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:26.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:65:0) after 1m0s
[05:23:26.977] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:65:0)
[05:23:26.977] Timer tick, asking Buildbucket for the build status
[05:23:27.111] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:27.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:66:0) after 1m0s
[05:24:27.132] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:66:0)
[05:24:27.132] Timer tick, asking Buildbucket for the build status
[05:24:27.374] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:27.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:67:0) after 1m0s
[05:25:27.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:67:0)
[05:25:27.429] Timer tick, asking Buildbucket for the build status
[05:25:27.543] Build 8862054986811666720: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:27.543] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027485049013481792:68:0) after 1m0s
[05:26:09.515] Received PubSub notification, asking Buildbucket for the build status
[05:26:09.644] Build 8862054986811666720: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:26:09.644] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1606886767971590",
"created_by": "project:chromium",
"created_ts": "1606882790705064",
"id": "8862054986811666720",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"d249b869e21325cf92d4d9f45486be807b2c20ee\"}}",
"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\": \"d249b869e21325cf92d4d9f45486be807b2c20ee\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15978, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"0c47015b57a03230e0cab10532c3deed80604512\", \"got_dawn_revision\": \"6f5151f6572bf22c88d0b3152bebeaa460ac4723\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"d249b869e21325cf92d4d9f45486be807b2c20ee\", \"got_revision_cp\": \"refs/heads/master@{#832673}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bfb211914759cc5deffbe9419cd1c2cae0573488\", \"got_v8_revision\": \"d4fd978f49d91844ea41f85afd8f3d917ef13e95\", \"got_v8_revision_cp\": \"refs/heads/8.9.74@{#1}\", \"got_webrtc_revision\": \"69662a99d30da64cbe064f5cc646bb75d77c0b71\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32735}\", \"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\": \"d249b869e21325cf92d4d9f45486be807b2c20ee\", \"swarm_hashes_refs/heads/master(at){#832673}_without_patch\": {\"absl_hardening_tests\": \"e681b10b6475bb2e77e11818f0c8cb756e47a35f\", \"accessibility_unittests\": \"9ee90e20543ebce5aa17395dd35e25d6a575e5f4\", \"angle_unittests\": \"988656a898b29d116aa957daad404086b5048ea9\", \"app_shell_unittests\": \"85076b299c0b2e717c1fa2bc5c4e662d494028a2\", \"aura_unittests\": \"388112c314cbe07ace857ce5faf257da03c7fa22\", \"base_unittests\": \"7cc6e0628d903f74fd0cfbe2d4487401c8f86126\", \"base_util_unittests\": \"17b7ddf77c7fcf629efe2a1c36c09650a9ea623c\", \"blink_common_unittests\": \"86cd929bc12ae7798780ca69d0295797c55b231a\", \"blink_fuzzer_unittests\": \"a93c216eeb67cbbc929a53c3a8d0319ce8679845\", \"blink_heap_unittests\": \"5c320d4e18f0d9452babe71085dd63bddd01632d\", \"blink_platform_unittests\": \"f236138d4176ce465aaf578761422e3e0f0fc2bd\", \"blink_unittests\": \"157d3814f9090d2a4529d893dc5ae359be826b6b\", \"boringssl_crypto_tests\": \"f2daf57956fbff490de91a20a9d7bc53cc1918aa\", \"boringssl_ssl_tests\": \"5c3a062cd1bc30cb6258109ba3c5c484cb8e6411\", \"browser_switcher_bho_unittests\": \"319659d2615ba1f5de7872552ef923236d3c037e\", \"browser_tests\": \"a3c0c7968cd5923b92ae6249b260f37dd5a2e1a1\", \"capture_unittests\": \"51862428a6e84806ca6a9ca7c1aea133e0a80351\", \"cast_unittests\": \"9037c73b31769e9198b1a78624c73fcde1fdb608\", \"cc_unittests\": \"420cdc73aa7e4ad5774dfe601162b0c063abe62d\", \"chrome_app_unittests\": \"2ab687b1df64eb9f0c5d457c35a3e08344de0067\", \"chrome_cleaner_unittests\": \"2bbfc84a89e6c60f6fb9a07f8f304215a0b50cfd\", \"chrome_elf_unittests\": \"cca3e344b4f365ee2953e94ce291ec24eb462aa9\", \"chromedriver_unittests\": \"e25e3817265e86f2d5d7d185f637a91f7901a117\", \"components_browsertests\": \"a9a13443567f435fe77ce85099c680bdab4d7dca\", \"components_unittests\": \"72e2b29539efc5731173cb97ae8e1fc5c9bfcca1\", \"compositor_unittests\": \"c024f607d1cae8c3062b6fcc5a98b11ee62d6cdf\", \"content_browsertests\": \"7d1147819df3b33cdd0311b6a6ec26509dbbe59d\", \"content_unittests\": \"7c38745362e7d038ddb44c811fa407c408dd155c\", \"courgette_unittests\": \"27da330c0ffc6e50f623a4f2f63f32eb94b951b0\", \"crashpad_tests\": \"e892ad184a8b7756175e59c243400ffa6ce1062e\", \"cronet_tests\": \"4be61b38707e00d8b0ff083a647427fd7d667ab5\", \"cronet_unittests\": \"e29952ae937127bd2bd5dc4357850c55c50766d1\", \"crypto_unittests\": \"3ee659816db222fb8c50154d278abd48f83a9d88\", \"delayloads_unittests\": \"cd8ea9faf236f244ab229ae2d41f53c6d07d97de\", \"device_unittests\": \"c0d34fee505d5c9c904156a26bacf91958343282\", \"display_unittests\": \"0a6f7e5ecb3b4a53b824c4e569c3f2106ec00cdb\", \"elevation_service_unittests\": \"ec10167e2d769284afde65f92952e9b1f80fbec4\", \"events_unittests\": \"0602a05d1b5b8266523a886bc3379b0f98c08b59\", \"extensions_browsertests\": \"fd3999b7aa43665bb92a3d4d99e28e845ee8f45c\", \"extensions_unittests\": \"4b92db366fae76baa607ab66a693762dea37e567\", \"filesystem_service_unittests\": \"8a3fc0a41f6ced8750fdaa86691868307701bae6\", \"gcm_unit_tests\": \"05e002d7353b6499b982c5f5bac00e1be99b74d2\", \"gcp_unittests\": \"c4bd9700b51a4706e97e07e3f7771fd064d8763f\", \"gfx_unittests\": \"de0f88900b2f04228bbec14c8db27468e4792589\", \"gin_unittests\": \"bf5af59eebfdeebbeca7109c9bb8e1663afdaab6\", \"google_apis_unittests\": \"3d27ce36553b26dd050bf2e33d4e61e67016fc1a\", \"gpu_unittests\": \"83954d4452a1c9fd71785d49316426866bccdb29\", \"gwp_asan_unittests\": \"0ad829f565fd9f0bb217451b27e495adff0703ac\", \"headless_browsertests\": \"28a58e9fd9b218683be99c4e7ff89ba63f204ed9\", \"headless_unittests\": \"6b34c3c76fd260344bfe05bb356d827b541dd7fe\", \"install_static_unittests\": \"f0250cb644acb9638157e2ccb3957ed657782335\", \"installer_util_unittests\": \"d1549a6c7825f7e8d1495f2d507efbb4fb7d66cf\", \"interactive_ui_tests\": \"60484fbb695fefbea19afa047d65567e60885468\", \"ipc_tests\": \"539d4f67f41af33a403e8f5a07808b3ee93f23bf\", \"jingle_unittests\": \"8671f9fb3aaa152d444a51e657241893bf37f0e6\", \"latency_unittests\": \"4d5a4ed6698f2a8e5be75f246c74e55111d07ccb\", \"libjingle_xmpp_unittests\": \"34677994326cadedf8e8496c7a4919523113b2a2\", \"liburlpattern_unittests\": \"2d6ca48c43a6347025eab7db37a67b8a3ae71b8f\", \"media_blink_unittests\": \"57ae65a4359c8e9cc931a971bf45c59884b8c222\", \"media_unittests\": \"f4165a0e24a73f7b5df0e60c8db0c7099e4996b5\", \"message_center_unittests\": \"f74ddeff0c90b0b2849304f2e1f47c6e0bb86ada\", \"midi_unittests\": \"9d8c229fbaa5ea4315bb6c39bd19e7d49c5f355f\", \"mojo_core_unittests\": \"56a7ac5e3f1ca1d65c4731697818e828096d18eb\", \"mojo_unittests\": \"231caf95c0375c970639bde0effdf44bb5ab5712\", \"nacl_loader_unittests\": \"7caf1495455c673b0728425480178bd652c83f6a\", \"native_theme_unittests\": \"254154d5b014569fb00df307b96ef48e387cd3f3\", \"net_unittests\": \"bc119840228af52a74a2c288bfb2210ca13cfd5b\", \"notification_helper_unittests\": \"67e3d96d65fbedf3767d5b29f38ba8d654023f6d\", \"pdf_unittests\": \"4ff0b18998ef65d618251adccf8d0903b2923d55\", \"perfetto_unittests\": \"f06882f55cc5668660bd7ca4be56378d7b7ac829\", \"ppapi_unittests\": \"1e8b8c6bfee6e89b92be4bb4477fdce57cd817bf\", \"printing_unittests\": \"1238754eb1df25710eaa8e1e1a8e9b528ab82112\", \"remoting_unittests\": \"6f09007a37a264a7a35358723791baa9e3d8541d\", \"sbox_integration_tests\": \"0fbfbfeb1df2df929df50a8b500d7aeb29cfb028\", \"sbox_unittests\": \"10a1669af9362a1076cef670ebd05df43d44017f\", \"sbox_validation_tests\": \"676d05f42038b65ed81131834a16dacbd7a06529\", \"service_manager_unittests\": \"9f99862465b19ee4d83f74518f0bf0bc43cb770e\", \"services_unittests\": \"aa6b0d44fd5d04304d804cab56c83dcf038488cb\", \"setup_unittests\": \"87af55719473674d391791b3481048f032f840b5\", \"shell_dialogs_unittests\": \"64f8beb26e9bc026da7cac961859163bbeae22d0\", \"skia_unittests\": \"0a3b0c030dcfe20365ee8714c43cb8a938bed596\", \"snapshot_unittests\": \"1a557b579518de4c44884ff580d40759f700072d\", \"sql_unittests\": \"6ca19195f4fc3b133ea2940b1f6fce984824bd45\", \"storage_unittests\": \"e942e03e2aa801ce1f7b3a917510c32a53a5f852\", \"sync_integration_tests\": \"b252032ff384099effc6e10fbb6e8167d16bd63e\", \"traffic_annotation_auditor_unittests\": \"216965d46de4155f0bb28781470506edc4781045\", \"ui_base_unittests\": \"669e55c654e386fb563f52741dfc7b0ed7b623c9\", \"ui_touch_selection_unittests\": \"400cc00fdd9e107e1272aab59cca0e6bfa9f5e2e\", \"unit_tests\": \"6f99fba00283d8bbdaf35d5e3fa0e508beb37b8a\", \"updater_tests\": \"fb9ec6399c2f668df07fb7bf5d19103bdfb999f9\", \"url_unittests\": \"2bb0977b8f47a57faf6295b206321799dbd62d71\", \"views_unittests\": \"fbc12b017a232d9a8d03f18a37d730cb890c65b1\", \"viz_unittests\": \"8898e0cd0858c51d4400a1e0581bd0aa44894d11\", \"vr_common_unittests\": \"2039617c80a1a4aeeeb28d5757c869353f8ed76a\", \"vr_pixeltests\": \"a68547c58cb22ea201705f610785a769f14ecc5e\", \"weblayer_browsertests\": \"39d5c50f7b17ef1f129f3c0dfe5c23f2a3f6c330\", \"weblayer_unittests\": \"60ca5f766a813dd51a7f64a76c24e887b0cf91e7\", \"wm_unittests\": \"6e834a8b542ef58fb3ceba1623afd2279c82768b\", \"wtf_unittests\": \"17913d0f3bdbc9537a0f26186d3d05dd1f034106\", \"zlib_unittests\": \"84c41be20e49585cf7e19386fe632c40be704ef3\", \"zucchini_unittests\": \"5e8d4c239254fa9a0e29bd29ff35b258d4efdb8d\"}}, \"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-9-v8md\"], \"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\": [\"5640-d0eb544\"], \"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": "1606882794879053",
"status": "COMPLETED",
"status_changed_ts": "1606886768686284",
"tags": [
"build_address:luci.chromium.ci/win-asan/15978",
"builder:win-asan",
"buildset:commit/git/d249b869e21325cf92d4d9f45486be807b2c20ee",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d249b869e21325cf92d4d9f45486be807b2c20ee",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027485049013481792",
"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/8862054986811666720/+/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:503a7f1b838d4910",
"user_agent:luci-scheduler"
],
"updated_ts": "1606886768690210",
"url": "https://ci.chromium.org/b/8862054986811666720",
"utcnow_ts": "1606886769629327"
}
}
[05:26:09.645] Invocation finished in 1h6m20.42222329s with status SUCCEEDED