[04:29:50.647] New invocation is queued and will start shortly
[04:29:51.820] Starting the invocation (attempt 1)
[04:29:51.851] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:29:51.851] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:29:51.851] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025581882096527328",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"17ac2f6437da361712be95bb66676c2ccc873f62\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025581882096527328",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/17ac2f6437da361712be95bb66676c2ccc873f62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17ac2f6437da361712be95bb66676c2ccc873f62",
"gitiles_ref:refs/heads/master"
]
}
[04:29:52.748] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608697791956782",
"id": "8860151820060538784",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17ac2f6437da361712be95bb66676c2ccc873f62\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608697792439187",
"tags": [
"build_address:luci.chromium.ci/win-asan/16399",
"builder:win-asan",
"buildset:commit/git/17ac2f6437da361712be95bb66676c2ccc873f62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17ac2f6437da361712be95bb66676c2ccc873f62",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025581882096527328",
"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/8860151820060538784/+/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": "1608697792439297",
"url": "https://ci.chromium.org/b/8860151820060538784",
"utcnow_ts": "1608697792741858"
}
}
[04:29:52.748] Task URL: https://ci.chromium.org/b/8860151820060538784
[04:29:52.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:2:0) after 1m0s
[04:29:57.033] Received PubSub notification, asking Buildbucket for the build status
[04:29:57.759] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:52.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:2:0)
[04:30:52.817] Timer tick, asking Buildbucket for the build status
[04:30:52.918] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:52.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:4:0) after 1m0s
[04:31:53.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:4:0)
[04:31:53.110] Timer tick, asking Buildbucket for the build status
[04:31:53.238] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:53.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:5:0) after 1m0s
[04:32:53.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:5:0)
[04:32:53.246] Timer tick, asking Buildbucket for the build status
[04:32:53.615] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:53.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:6:0) after 1m0s
[04:33:53.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:6:0)
[04:33:53.671] Timer tick, asking Buildbucket for the build status
[04:33:54.035] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:54.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:7:0) after 1m0s
[04:34:54.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:7:0)
[04:34:54.057] Timer tick, asking Buildbucket for the build status
[04:34:54.200] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:54.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:8:0) after 1m0s
[04:35:54.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:8:0)
[04:35:54.238] Timer tick, asking Buildbucket for the build status
[04:35:54.364] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:54.364] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:9:0) after 1m0s
[04:36:54.441] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:9:0)
[04:36:54.441] Timer tick, asking Buildbucket for the build status
[04:36:54.579] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:54.579] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:10:0) after 1m0s
[04:37:54.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:10:0)
[04:37:54.653] Timer tick, asking Buildbucket for the build status
[04:37:54.758] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:54.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:11:0) after 1m0s
[04:38:54.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:11:0)
[04:38:54.832] Timer tick, asking Buildbucket for the build status
[04:38:54.886] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:54.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:12:0) after 1m0s
[04:39:54.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:12:0)
[04:39:54.933] Timer tick, asking Buildbucket for the build status
[04:39:55.174] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:55.174] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:13:0) after 1m0s
[04:40:55.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:13:0)
[04:40:55.266] Timer tick, asking Buildbucket for the build status
[04:40:55.727] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:55.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:14:0) after 1m0s
[04:41:55.769] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:14:0)
[04:41:55.769] Timer tick, asking Buildbucket for the build status
[04:41:57.113] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:57.113] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:15:0) after 1m0s
[04:42:57.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:15:0)
[04:42:57.156] Timer tick, asking Buildbucket for the build status
[04:42:57.215] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:57.216] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:16:0) after 1m0s
[04:43:57.213] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:16:0)
[04:43:57.213] Timer tick, asking Buildbucket for the build status
[04:43:57.343] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:57.343] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:17:0) after 1m0s
[04:44:57.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:17:0)
[04:44:57.396] Timer tick, asking Buildbucket for the build status
[04:44:57.833] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:57.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:18:0) after 1m0s
[04:45:57.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:18:0)
[04:45:57.939] Timer tick, asking Buildbucket for the build status
[04:45:58.114] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:58.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:19:0) after 1m0s
[04:46:58.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:19:0)
[04:46:58.183] Timer tick, asking Buildbucket for the build status
[04:46:58.333] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:58.333] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:20:0) after 1m0s
[04:47:58.351] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:20:0)
[04:47:58.351] Timer tick, asking Buildbucket for the build status
[04:47:58.506] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:58.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:21:0) after 1m0s
[04:48:58.489] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:21:0)
[04:48:58.489] Timer tick, asking Buildbucket for the build status
[04:48:58.603] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:58.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:22:0) after 1m0s
[04:49:58.715] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:22:0)
[04:49:58.715] Timer tick, asking Buildbucket for the build status
[04:49:58.866] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:58.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:23:0) after 1m0s
[04:50:58.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:23:0)
[04:50:58.964] Timer tick, asking Buildbucket for the build status
[04:50:59.068] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:59.068] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:24:0) after 1m0s
[04:51:59.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:24:0)
[04:51:59.157] Timer tick, asking Buildbucket for the build status
[04:51:59.353] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:59.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:25:0) after 1m0s
[04:52:59.331] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:25:0)
[04:52:59.331] Timer tick, asking Buildbucket for the build status
[04:52:59.456] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:59.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:26:0) after 1m0s
[04:53:59.427] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:26:0)
[04:53:59.427] Timer tick, asking Buildbucket for the build status
[04:53:59.548] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:59.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:27:0) after 1m0s
[04:54:59.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:27:0)
[04:54:59.622] Timer tick, asking Buildbucket for the build status
[04:54:59.737] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:59.737] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:28:0) after 1m0s
[04:55:59.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:28:0)
[04:55:59.776] Timer tick, asking Buildbucket for the build status
[04:55:59.898] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:59.898] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:29:0) after 1m0s
[04:56:59.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:29:0)
[04:56:59.923] Timer tick, asking Buildbucket for the build status
[04:56:59.981] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:59.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:30:0) after 1m0s
[04:58:00.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:30:0)
[04:58:00.053] Timer tick, asking Buildbucket for the build status
[04:58:00.892] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:00.892] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:31:0) after 1m0s
[04:59:00.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:31:0)
[04:59:00.927] Timer tick, asking Buildbucket for the build status
[04:59:01.193] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:01.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:32:0) after 1m0s
[05:00:01.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:32:0)
[05:00:01.332] Timer tick, asking Buildbucket for the build status
[05:00:01.469] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:01.469] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:33:0) after 1m0s
[05:01:01.529] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:33:0)
[05:01:01.529] Timer tick, asking Buildbucket for the build status
[05:01:01.711] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:01.711] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:34:0) after 1m0s
[05:02:02.126] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:34:0)
[05:02:02.126] Timer tick, asking Buildbucket for the build status
[05:02:02.249] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:02.249] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:35:0) after 1m0s
[05:03:02.273] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:35:0)
[05:03:02.273] Timer tick, asking Buildbucket for the build status
[05:03:02.359] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:02.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:36:0) after 1m0s
[05:04:02.423] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:36:0)
[05:04:02.423] Timer tick, asking Buildbucket for the build status
[05:04:02.527] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:02.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:37:0) after 1m0s
[05:05:02.598] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:37:0)
[05:05:02.598] Timer tick, asking Buildbucket for the build status
[05:05:02.888] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:02.888] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:38:0) after 1m0s
[05:06:02.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:38:0)
[05:06:02.944] Timer tick, asking Buildbucket for the build status
[05:06:03.095] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:03.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:39:0) after 1m0s
[05:07:03.149] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:39:0)
[05:07:03.149] Timer tick, asking Buildbucket for the build status
[05:07:03.320] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:03.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:40:0) after 1m0s
[05:08:03.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:40:0)
[05:08:03.408] Timer tick, asking Buildbucket for the build status
[05:08:03.494] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:03.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:41:0) after 1m0s
[05:09:03.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:41:0)
[05:09:03.483] Timer tick, asking Buildbucket for the build status
[05:09:03.808] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:03.808] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:42:0) after 1m0s
[05:10:03.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:42:0)
[05:10:03.889] Timer tick, asking Buildbucket for the build status
[05:10:04.055] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:04.055] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:43:0) after 1m0s
[05:11:04.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:43:0)
[05:11:04.118] Timer tick, asking Buildbucket for the build status
[05:11:06.157] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:06.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:44:0) after 1m0s
[05:12:06.225] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:44:0)
[05:12:06.225] Timer tick, asking Buildbucket for the build status
[05:12:06.368] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:06.368] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:45:0) after 1m0s
[05:13:06.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:45:0)
[05:13:06.469] Timer tick, asking Buildbucket for the build status
[05:13:07.088] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:07.088] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:46:0) after 1m0s
[05:14:07.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:46:0)
[05:14:07.109] Timer tick, asking Buildbucket for the build status
[05:14:07.856] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:07.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:47:0) after 1m0s
[05:15:07.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:47:0)
[05:15:07.887] Timer tick, asking Buildbucket for the build status
[05:15:09.603] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:09.603] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:48:0) after 1m0s
[05:16:09.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:48:0)
[05:16:09.714] Timer tick, asking Buildbucket for the build status
[05:16:09.793] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:09.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:49:0) after 1m0s
[05:17:11.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:49:0)
[05:17:11.111] Timer tick, asking Buildbucket for the build status
[05:17:11.321] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:11.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:50:0) after 1m0s
[05:18:11.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:50:0)
[05:18:11.561] Timer tick, asking Buildbucket for the build status
[05:18:11.641] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:11.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:51:0) after 1m0s
[05:19:11.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:51:0)
[05:19:11.811] Timer tick, asking Buildbucket for the build status
[05:19:11.884] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:11.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:52:0) after 1m0s
[05:20:11.904] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:52:0)
[05:20:11.904] Timer tick, asking Buildbucket for the build status
[05:20:12.139] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:12.139] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:53:0) after 1m0s
[05:21:12.169] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:53:0)
[05:21:12.169] Timer tick, asking Buildbucket for the build status
[05:21:12.423] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:12.423] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:54:0) after 1m0s
[05:22:12.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:54:0)
[05:22:12.508] Timer tick, asking Buildbucket for the build status
[05:22:12.590] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:12.590] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:55:0) after 1m0s
[05:23:12.697] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:55:0)
[05:23:12.697] Timer tick, asking Buildbucket for the build status
[05:23:13.212] Build 8860151820060538784: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:13.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025581882096527328:56:0) after 1m0s
[05:23:55.277] Received PubSub notification, asking Buildbucket for the build status
[05:23:55.355] Build 8860151820060538784: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:23:55.355] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608701032597712",
"created_by": "project:chromium",
"created_ts": "1608697791956782",
"id": "8860151820060538784",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"17ac2f6437da361712be95bb66676c2ccc873f62\"}}",
"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\": \"17ac2f6437da361712be95bb66676c2ccc873f62\", \"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\": 16399, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"74788951af42f8f4f360bb9471cb3906379bdb7c\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"17ac2f6437da361712be95bb66676c2ccc873f62\", \"got_revision_cp\": \"refs/heads/master@{#839053}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"407251b826bdb2683e639456c81b4a82de640240\", \"got_v8_revision_cp\": \"refs/heads/8.9.185@{#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\": \"17ac2f6437da361712be95bb66676c2ccc873f62\", \"swarm_hashes_refs/heads/master(at){#839053}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"8b0e2bff2283472c8855cb7cd0cc2cfdb858e62f\", \"angle_unittests\": \"e2f544dff5a92f1c79a764780db3eaa30f31ec18\", \"app_shell_unittests\": \"c2626d0bd21f06be3a1b20a4b6169f1679328821\", \"aura_unittests\": \"fab2468f41c4862d32664e5e63c4315b1e5ed950\", \"base_unittests\": \"7ca7a1bb35aac42e0d7146e65d0f45bccbd8b1d1\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"a8b82406691f16d1363f7387bc2a77ff080f8b69\", \"blink_fuzzer_unittests\": \"70e5eb598a6f575e7e907271aa6ff7bcba68dadc\", \"blink_heap_unittests\": \"118db4cbdbb2e4a3da964b448922afda63cb7e2a\", \"blink_platform_unittests\": \"2d5027e7123c524ae64ff63f058da8448868369a\", \"blink_unittests\": \"02ab849023cefdedf1699468e5c77f1c33acec17\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"2df964f30348eeb8ce1a118dc8f6ed2f02f59fd5\", \"capture_unittests\": \"85a5d1ecfdb0256fca2925681302f014965c5e99\", \"cast_unittests\": \"141e0c9e1272ec99e51c9a3f1b6d1301e81b8cb2\", \"cc_unittests\": \"47fdde0ac1eca390fc7623a992e4ebdf5ab85db4\", \"chrome_app_unittests\": \"9b66dc1087b89ed12e85b68b9afb651325577861\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"bf435bc7b9bea3f0b32e81ab86d814aa47fd4c0f\", \"chromedriver_unittests\": \"2edc530fa49608dd1812122675b319b70f10fb0f\", \"components_browsertests\": \"affe26ade1520c3af1655f54eab866ea688f5007\", \"components_unittests\": \"2a29e232e1532e5fb3b65707c00d7512cc9accbc\", \"compositor_unittests\": \"acfbc1028601cae640a3263e59529f6f5466416b\", \"content_browsertests\": \"80b9719f2bd5e738a5c6ed412d44db0b4571176f\", \"content_unittests\": \"6ea3915e75e34ab60d7ed30551a218fb501f3a6b\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"a66c5e82820c6aafe9fff00acc9598d9cd37b6e7\", \"device_unittests\": \"3db78698bbc5922486176a1bb5e3589e68f9ee45\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"1a2fa06790a21d69e02ea541951a3f9159874e21\", \"events_unittests\": \"e6f297aa3189eec58f987af8e6b64c0b31db7ba9\", \"extensions_browsertests\": \"b8c11ff7810489a3a3f4b7faf6a07548fbd947f4\", \"extensions_unittests\": \"f1aa87ee31cb519461fa30cee4c106784edac56e\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"c566a80b34af4cbd2350cf8088f4f36d3d9a05c5\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"bef1678a830268bf30c7fc9feeb06012eb490d5b\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"9d4f87200956c684b57aaa027531272733096b07\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"7a7bbe59897d8c4f623d41c3ebc4503dbb80784c\", \"headless_unittests\": \"9431c54eb3f44823f13f4723a21b15c23ded8e1b\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"5d67afc36cff5d6d12a4064e5f0ecf759b583760\", \"interactive_ui_tests\": \"94e7f358b0352cc9621c0229edff8e4059f61fe1\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"8826c94df46d2f383536074681eebd0a866affef\", \"media_unittests\": \"5d6b0abd270d6b5318592107be6b9e98288663ed\", \"message_center_unittests\": \"6447f1d3f7b4b3be6c18ca7703ecc53ad2260db2\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"45c52dbf3450d5915d551e3d1c90f29261401246\", \"nacl_loader_unittests\": \"64914d27620176029a8e38c7afcc046e573da965\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"238b080f58961bd72e99a080eb58efb2f3efa579\", \"pdf_unittests\": \"e525f98d4ade8c56ca5d7877fe5068d400206ace\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"321339d8b1831a26b9718aa7ca4829a45142b26f\", \"printing_unittests\": \"0a935b00eef420d1b2733978aae42b4557081ace\", \"remoting_unittests\": \"39c12de1c67a3fbcde9c849a7f22a6a853f8ad4d\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"930fa792614c174d00d3994f547ae443495a6784\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"be95e4f31b09a7d38c7db4e6a894616c634ea592\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"273cd266d59bfd87b62ef8e8f7d22ce78312de54\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"75fbedd7d1a8e4813be935e709fc1f53cc03e09d\", \"sync_integration_tests\": \"8e06526b6169496bc379b5c5aa4d9582e80ccc9a\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"e02e702d5d209a3b1b5945695a4f17ad0b8ed1fc\", \"ui_touch_selection_unittests\": \"f31e1092da3843548512d96c603a3bca606d8de0\", \"unit_tests\": \"9daf168e3008f18034fca6e2bc934b512f70a184\", \"updater_tests\": \"d422e0080ebebe1bbb211c13ad9476a8e81180c5\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"6e14ac39ee15431d373175c48ac432410096e4c3\", \"viz_unittests\": \"aad552b1b4bc2aa5aa63b48a3c9413844e476f51\", \"vr_common_unittests\": \"26daa695df46c438aafe2c56d608f67026197d45\", \"vr_pixeltests\": \"298e02188d40637f35229f03dfbad5834797a470\", \"weblayer_browsertests\": \"ba5ab86199cd594048877d4f2f0c5e64e7a72990\", \"weblayer_unittests\": \"2cfd68fd94af26cfdaf00c33de16183232eb52ac\", \"wm_unittests\": \"6e350778cfacb77a217195aec3dd20a6b5f72655\", \"wtf_unittests\": \"43e608e4120b838e240032883f0ba0d3b6ba0f01\", \"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": "1608697796228799",
"status": "COMPLETED",
"status_changed_ts": "1608701033499805",
"tags": [
"build_address:luci.chromium.ci/win-asan/16399",
"builder:win-asan",
"buildset:commit/git/17ac2f6437da361712be95bb66676c2ccc873f62",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/17ac2f6437da361712be95bb66676c2ccc873f62",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025581882096527328",
"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/8860151820060538784/+/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:50a6add5aa220010",
"user_agent:luci-scheduler"
],
"updated_ts": "1608701033525757",
"url": "https://ci.chromium.org/b/8860151820060538784",
"utcnow_ts": "1608701035347084"
}
}
[05:23:55.355] Invocation finished in 54m4.72500607s with status SUCCEEDED