[15:30:45.423] New invocation is queued and will start shortly
[15:30:48.287] Starting the invocation (attempt 1)
[15:30:48.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:30:48.374] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:30:48.374] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9028892388809648176",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9028892388809648176",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb",
"gitiles_ref:refs/heads/master"
]
}
[15:30:48.969] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1605540648520303",
"id": "8863462324895715104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1605540648849224",
"tags": [
"build_address:luci.chromium.ci/win-asan/15669",
"builder:win-asan",
"buildset:commit/git/0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028892388809648176",
"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/8863462324895715104/+/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": "1605540648849353",
"url": "https://ci.chromium.org/b/8863462324895715104",
"utcnow_ts": "1605540648955056"
}
}
[15:30:48.969] Task URL: https://ci.chromium.org/b/8863462324895715104
[15:30:48.969] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:2:0) after 1m0s
[15:30:54.259] Received PubSub notification, asking Buildbucket for the build status
[15:30:54.542] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:49.142] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:2:0)
[15:31:49.142] Timer tick, asking Buildbucket for the build status
[15:31:49.234] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:31:49.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:4:0) after 1m0s
[15:32:49.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:4:0)
[15:32:49.396] Timer tick, asking Buildbucket for the build status
[15:32:49.513] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:32:49.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:5:0) after 1m0s
[15:33:49.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:5:0)
[15:33:49.633] Timer tick, asking Buildbucket for the build status
[15:33:49.979] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:33:49.979] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:6:0) after 1m0s
[15:34:50.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:6:0)
[15:34:50.155] Timer tick, asking Buildbucket for the build status
[15:34:50.248] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:34:50.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:7:0) after 1m0s
[15:35:50.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:7:0)
[15:35:50.479] Timer tick, asking Buildbucket for the build status
[15:35:50.588] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:35:50.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:8:0) after 1m0s
[15:36:50.705] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:8:0)
[15:36:50.705] Timer tick, asking Buildbucket for the build status
[15:36:50.819] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:36:50.819] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:9:0) after 1m0s
[15:37:50.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:9:0)
[15:37:50.897] Timer tick, asking Buildbucket for the build status
[15:37:51.111] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:37:51.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:10:0) after 1m0s
[15:38:51.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:10:0)
[15:38:51.148] Timer tick, asking Buildbucket for the build status
[15:38:51.256] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:38:51.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:11:0) after 1m0s
[15:39:51.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:11:0)
[15:39:51.274] Timer tick, asking Buildbucket for the build status
[15:39:53.022] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:39:53.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:12:0) after 1m0s
[15:40:53.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:12:0)
[15:40:53.076] Timer tick, asking Buildbucket for the build status
[15:40:53.207] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:40:53.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:13:0) after 1m0s
[15:41:53.272] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:13:0)
[15:41:53.272] Timer tick, asking Buildbucket for the build status
[15:41:53.348] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:41:53.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:14:0) after 1m0s
[15:42:53.488] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:14:0)
[15:42:53.488] Timer tick, asking Buildbucket for the build status
[15:42:53.624] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:42:53.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:15:0) after 1m0s
[15:43:53.658] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:15:0)
[15:43:53.658] Timer tick, asking Buildbucket for the build status
[15:43:54.013] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:43:54.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:16:0) after 1m0s
[15:44:54.044] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:16:0)
[15:44:54.045] Timer tick, asking Buildbucket for the build status
[15:44:54.782] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:44:54.782] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:17:0) after 1m0s
[15:45:54.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:17:0)
[15:45:54.923] Timer tick, asking Buildbucket for the build status
[15:45:55.038] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:55.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:18:0) after 1m0s
[15:46:55.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:18:0)
[15:46:55.269] Timer tick, asking Buildbucket for the build status
[15:46:55.401] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:55.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:19:0) after 1m0s
[15:47:55.418] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:19:0)
[15:47:55.418] Timer tick, asking Buildbucket for the build status
[15:47:55.637] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:55.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:20:0) after 1m0s
[15:48:55.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:20:0)
[15:48:55.666] Timer tick, asking Buildbucket for the build status
[15:48:56.006] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:56.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:21:0) after 1m0s
[15:49:56.057] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:21:0)
[15:49:56.057] Timer tick, asking Buildbucket for the build status
[15:49:56.714] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:56.714] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:22:0) after 1m0s
[15:50:56.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:22:0)
[15:50:56.824] Timer tick, asking Buildbucket for the build status
[15:50:56.942] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:56.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:23:0) after 1m0s
[15:51:56.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:23:0)
[15:51:56.955] Timer tick, asking Buildbucket for the build status
[15:51:57.106] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:57.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:24:0) after 1m0s
[15:52:57.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:24:0)
[15:52:57.150] Timer tick, asking Buildbucket for the build status
[15:52:57.241] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:57.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:25:0) after 1m0s
[15:53:57.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:25:0)
[15:53:57.291] Timer tick, asking Buildbucket for the build status
[15:53:57.464] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:57.464] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:26:0) after 1m0s
[15:54:58.160] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:26:0)
[15:54:58.160] Timer tick, asking Buildbucket for the build status
[15:54:58.290] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:58.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:27:0) after 1m0s
[15:55:58.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:27:0)
[15:55:58.563] Timer tick, asking Buildbucket for the build status
[15:55:58.735] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:58.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:28:0) after 1m0s
[15:56:59.083] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:28:0)
[15:56:59.083] Timer tick, asking Buildbucket for the build status
[15:56:59.211] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:59.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:29:0) after 1m0s
[15:58:00.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:29:0)
[15:58:00.622] Timer tick, asking Buildbucket for the build status
[15:58:00.775] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:00.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:30:0) after 1m0s
[15:59:00.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:30:0)
[15:59:00.893] Timer tick, asking Buildbucket for the build status
[15:59:01.085] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:01.085] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:31:0) after 1m0s
[16:00:01.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:31:0)
[16:00:01.125] Timer tick, asking Buildbucket for the build status
[16:00:01.251] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:01.251] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:32:0) after 1m0s
[16:01:01.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:32:0)
[16:01:01.852] Timer tick, asking Buildbucket for the build status
[16:01:02.633] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:02.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:33:0) after 1m0s
[16:02:02.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:33:0)
[16:02:02.700] Timer tick, asking Buildbucket for the build status
[16:02:02.813] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:02.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:34:0) after 1m0s
[16:03:02.839] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:34:0)
[16:03:02.839] Timer tick, asking Buildbucket for the build status
[16:03:02.984] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:02.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:35:0) after 1m0s
[16:04:03.220] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:35:0)
[16:04:03.220] Timer tick, asking Buildbucket for the build status
[16:04:03.944] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:03.944] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:36:0) after 1m0s
[16:05:04.095] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:36:0)
[16:05:04.095] Timer tick, asking Buildbucket for the build status
[16:05:04.287] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:04.287] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:37:0) after 1m0s
[16:06:04.392] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:37:0)
[16:06:04.392] Timer tick, asking Buildbucket for the build status
[16:06:04.910] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:04.910] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:38:0) after 1m0s
[16:07:04.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:38:0)
[16:07:04.989] Timer tick, asking Buildbucket for the build status
[16:07:05.395] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:05.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:39:0) after 1m0s
[16:08:05.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:39:0)
[16:08:05.531] Timer tick, asking Buildbucket for the build status
[16:08:05.675] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:05.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:40:0) after 1m0s
[16:09:05.687] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:40:0)
[16:09:05.687] Timer tick, asking Buildbucket for the build status
[16:09:06.209] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:06.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:41:0) after 1m0s
[16:10:06.301] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:41:0)
[16:10:06.301] Timer tick, asking Buildbucket for the build status
[16:10:06.574] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:06.574] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:42:0) after 1m0s
[16:11:06.686] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:42:0)
[16:11:06.686] Timer tick, asking Buildbucket for the build status
[16:11:07.143] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:07.143] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:43:0) after 1m0s
[16:12:07.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:43:0)
[16:12:07.215] Timer tick, asking Buildbucket for the build status
[16:12:07.290] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:07.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:44:0) after 1m0s
[16:13:07.324] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:44:0)
[16:13:07.324] Timer tick, asking Buildbucket for the build status
[16:13:07.391] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:07.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:45:0) after 1m0s
[16:14:07.515] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:45:0)
[16:14:07.515] Timer tick, asking Buildbucket for the build status
[16:14:07.735] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:07.735] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:46:0) after 1m0s
[16:15:07.804] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:46:0)
[16:15:07.804] Timer tick, asking Buildbucket for the build status
[16:15:07.921] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:07.921] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:47:0) after 1m0s
[16:16:07.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:47:0)
[16:16:07.957] Timer tick, asking Buildbucket for the build status
[16:16:08.096] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:08.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:48:0) after 1m0s
[16:17:08.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:48:0)
[16:17:08.119] Timer tick, asking Buildbucket for the build status
[16:17:08.307] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:08.307] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:49:0) after 1m0s
[16:18:08.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:49:0)
[16:18:08.377] Timer tick, asking Buildbucket for the build status
[16:18:08.566] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:08.566] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:50:0) after 1m0s
[16:19:08.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:50:0)
[16:19:08.594] Timer tick, asking Buildbucket for the build status
[16:19:09.403] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:09.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:51:0) after 1m0s
[16:20:09.466] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:51:0)
[16:20:09.466] Timer tick, asking Buildbucket for the build status
[16:20:09.619] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:09.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:52:0) after 1m0s
[16:21:09.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:52:0)
[16:21:09.653] Timer tick, asking Buildbucket for the build status
[16:21:09.774] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:09.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:53:0) after 1m0s
[16:22:09.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:53:0)
[16:22:09.888] Timer tick, asking Buildbucket for the build status
[16:22:09.960] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:09.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:54:0) after 1m0s
[16:23:10.122] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:54:0)
[16:23:10.122] Timer tick, asking Buildbucket for the build status
[16:23:10.516] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:10.516] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:55:0) after 1m0s
[16:24:10.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:55:0)
[16:24:10.627] Timer tick, asking Buildbucket for the build status
[16:24:10.925] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:10.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:56:0) after 1m0s
[16:25:10.948] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:56:0)
[16:25:10.948] Timer tick, asking Buildbucket for the build status
[16:25:11.027] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:11.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:57:0) after 1m0s
[16:26:11.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:57:0)
[16:26:11.182] Timer tick, asking Buildbucket for the build status
[16:26:11.308] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:11.308] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:58:0) after 1m0s
[16:27:11.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:58:0)
[16:27:11.381] Timer tick, asking Buildbucket for the build status
[16:27:11.463] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:11.463] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:59:0) after 1m0s
[16:28:11.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:59:0)
[16:28:11.548] Timer tick, asking Buildbucket for the build status
[16:28:11.638] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:11.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:60:0) after 1m0s
[16:29:11.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:60:0)
[16:29:11.743] Timer tick, asking Buildbucket for the build status
[16:29:11.811] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:11.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:61:0) after 1m0s
[16:30:11.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:61:0)
[16:30:11.861] Timer tick, asking Buildbucket for the build status
[16:30:11.966] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:11.966] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:62:0) after 1m0s
[16:31:11.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:62:0)
[16:31:11.968] Timer tick, asking Buildbucket for the build status
[16:31:13.016] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:13.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:63:0) after 1m0s
[16:32:13.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:63:0)
[16:32:13.035] Timer tick, asking Buildbucket for the build status
[16:32:13.161] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:13.161] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:64:0) after 1m0s
[16:33:13.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:64:0)
[16:33:13.214] Timer tick, asking Buildbucket for the build status
[16:33:13.362] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:13.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:65:0) after 1m0s
[16:34:13.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:65:0)
[16:34:13.366] Timer tick, asking Buildbucket for the build status
[16:34:13.518] Build 8863462324895715104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:13.518] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9028892388809648176:66:0) after 1m0s
[16:34:37.859] Received PubSub notification, asking Buildbucket for the build status
[16:34:37.971] Build 8863462324895715104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:34:37.971] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1605544477218402",
"created_by": "project:chromium",
"created_ts": "1605540648520303",
"id": "8863462324895715104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb\"}}",
"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\": \"0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb\", \"project\": \"chromium/src\"}], \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\s\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"bot_id\": \"luci-chromium-ci-win10-32-3-n4wf\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15669, \"clang_revision\": \"llvmorg-12-init-11462-g418f18c6-1\", \"got_angle_revision\": \"f46e6201b40bdee3e0b79d5d97660fc302fd76f5\", \"got_dawn_revision\": \"575729e8dd25e0ac4631c9739a82118ea3545c6b\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb\", \"got_revision_cp\": \"refs/heads/master@{#827780}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"e4c1a25cc6797a47043fc415c53b8edcd7b3e37e\", \"got_v8_revision\": \"2dbcdc105b963ee2501c82139eef7e0603977ff0\", \"got_v8_revision_cp\": \"refs/heads/8.8.278@{#1}\", \"got_webrtc_revision\": \"62a9a329373a8dfe201cfce7e1278916e9ed9637\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32612}\", \"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\": \"0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb\", \"swarm_hashes_refs/heads/master(at){#827780}_without_patch\": {\"absl_hardening_tests\": \"3545a3801b0a7c9d6aee4e33719c5f5f001ecd13\", \"accessibility_unittests\": \"97175cd1e3d635bc7e196ab5afa51746eea2793f\", \"angle_unittests\": \"8ed87780b3ba7eeb9cada21d082c5dfa44f6c5e5\", \"app_shell_unittests\": \"4912af608af5a6596d23b19da8bf99e1ee05188e\", \"aura_unittests\": \"6aca246a44021aa47bfc1585c9fd4e3af52a85e1\", \"base_unittests\": \"2164830356574053f4b6a936a12a88c921d76dbc\", \"base_util_unittests\": \"058ddd4df25f509e37b8538ecb142a96107ce3c5\", \"blink_common_unittests\": \"8b0b19eac8556591a2967181ec1a08b78f1c11ff\", \"blink_fuzzer_unittests\": \"aa3e213d0bfc150285b5eb5b407959dc6d0e3067\", \"blink_heap_unittests\": \"ded898bd6393d984ece149bb1c23ca407152d89c\", \"blink_platform_unittests\": \"68329dd073750b23a1ec8cda217aebfe6cec1fa9\", \"blink_unittests\": \"f5d151938d32194d7e8c0acfb56f2d2cb857c594\", \"boringssl_crypto_tests\": \"94add36fde0b1dd2349bb3d28fa9252849b1320a\", \"boringssl_ssl_tests\": \"557b0faddf6c51d5db46a4933494bc237650fa2c\", \"browser_switcher_bho_unittests\": \"ae14cacf3a44aa824584c941f051729d1fc1937d\", \"browser_tests\": \"9d671b40860f0eaefd47cb5fc74099b8c60f6149\", \"capture_unittests\": \"e3783e9e34e403ed867fb5f8f2748dd1c024f9e1\", \"cast_unittests\": \"87c29ad528a0c64a358a7d69dc7f95ca795429bc\", \"cc_unittests\": \"9fb77b5ea8c38ddd2a1710a7c81ae5c8671d4380\", \"chrome_app_unittests\": \"d317a0aae6293eb91dcd11ff9d6497f39c72ea4d\", \"chrome_cleaner_unittests\": \"2dd26933390fd4cfb0e7116ee3b0630e3a1d55c9\", \"chrome_elf_unittests\": \"1f00dc4b138cb8b09c45b5762b2de9c1f28955f1\", \"chromedriver_unittests\": \"42507a72f6a7fa5da7e2e1191d2e4ae4aadaabb6\", \"components_browsertests\": \"cae6b74822c8be1cfa315f53e9a3285529a9b25d\", \"components_unittests\": \"6cb374caf02580f092020be4769addd8f1e1a349\", \"compositor_unittests\": \"d55a6c209e918d6315694f0a230ee11169eadf8b\", \"content_browsertests\": \"95058adb374c269266e6700a6f0a2a17a7fe7853\", \"content_unittests\": \"d2564b4805f31f1707fac0148b275940780755e5\", \"courgette_unittests\": \"1125b25c77693136842cc820ab99c40f35380e6e\", \"crashpad_tests\": \"5aeb0e8f1c07a5c3badaf509386722a05bf665ff\", \"cronet_tests\": \"4e9a6613504978ac881c54f02ab4ad05138d8a39\", \"cronet_unittests\": \"5c52a663982f312d5cc3be311fe05952c9ccc0bd\", \"crypto_unittests\": \"354e3243fcbc8cb3f83ef450822b5aecb85cf622\", \"delayloads_unittests\": \"425eae969b0ba120f2d304a5c32e05c0db8d8379\", \"device_unittests\": \"84585efbacbb448ec2d1dab99fd52d5a5d4ddf21\", \"display_unittests\": \"1d5f2a5678a205940e0ccc0e641ae94f7aaca971\", \"elevation_service_unittests\": \"fd17dd991eca9a6bf51efc49e3c65179034d7f6a\", \"events_unittests\": \"17c56b71bec02c87e7ea7420b631f89b1fe268f6\", \"extensions_browsertests\": \"f565f55338e85dc4c7b357ad47d2fe6920a0ea6c\", \"extensions_unittests\": \"8380f261c38be3ebb1980bc9949bd83d832deabb\", \"filesystem_service_unittests\": \"ec13602480728a7b61b83afa6444c6982de4b784\", \"gcm_unit_tests\": \"ebd272cf03295f357dcbebf1dcf63a8ca20439eb\", \"gcp_unittests\": \"e917548548bddad6d1ab03acc53af6c60b493a93\", \"gfx_unittests\": \"506ef5374b5990be898cc96ec8e63b4ccd3b9790\", \"gin_unittests\": \"5e5807b4cb14bde5e3c8f6c5b38d456880c717f0\", \"google_apis_unittests\": \"5a084b8ad31c5d5bcc26609d0358ca3b3ba9a783\", \"gpu_unittests\": \"1d0985f5bdafd727bc48e8cb9ed4bcb5896c9048\", \"gwp_asan_unittests\": \"b8b0189b5c5ae06124f3c9b83e4a18d618cc5459\", \"headless_browsertests\": \"28256c339d8c9fe12e5221d1456f5ffac1c933e6\", \"headless_unittests\": \"eb624871552e125b37706035d4f5a5097723bb07\", \"install_static_unittests\": \"fd56b5b5bbceb9ac76784c573375cc857aad538c\", \"installer_util_unittests\": \"b85ddb183e8a79149e8e2adf4e190ffea8b8fea5\", \"interactive_ui_tests\": \"1c664fc8921334d9b87931ffe9aace04ce9a7979\", \"ipc_tests\": \"031295421a3fe0182b440f25db80de9bf0a22a89\", \"jingle_unittests\": \"c147f1da8e04d10105d95bc98fcbd7d5779ea0aa\", \"latency_unittests\": \"aa7f62193d7fa52f9e38cce4b7ed1dc3ac15e105\", \"libjingle_xmpp_unittests\": \"5fdbad0fe203fe455d8892c5b8180290c25aa5a6\", \"liburlpattern_unittests\": \"553a8ce77b161e35b1ae0b947d6a4e9ac8faa8dc\", \"media_blink_unittests\": \"c70b39831469357d1136c72c9a79b81d19c6ef02\", \"media_unittests\": \"1891ad92f7513e8f2941d9d9e1cea1c7afbc9c22\", \"message_center_unittests\": \"d5d537fd45b2ce61a76c0b64a8267ea6f537d5cc\", \"midi_unittests\": \"313fdae6e46b3b325ea85e09df8b5ebdbf3fd075\", \"mojo_core_unittests\": \"21aae869f1ac7a25d5325aa055c767db14d4c12f\", \"mojo_unittests\": \"4ebe6a8d121dc689f5ff6e1faf2121be66b65873\", \"nacl_loader_unittests\": \"0f62ce5a72ec489b1df99ef29fc5205089446134\", \"native_theme_unittests\": \"3c49435f09a7e8706c6c51126dc3a429365f2a7a\", \"net_unittests\": \"55aaf63145ab505f7d80d8ff3fc31958886af75c\", \"notification_helper_unittests\": \"3c51d35ba4534be2d294e74392d3ce5b596f7268\", \"pdf_unittests\": \"9f82bcabd81e282d2f3cd0159a9fd393924da513\", \"perfetto_unittests\": \"e0f68d014669170b1684d2dccb618f29b55c56bf\", \"ppapi_unittests\": \"05d8e7c0c80ab3fbbe5a2fa9d7718cab8996a923\", \"printing_unittests\": \"de5b7df522d2289e1e1421b1b5d0ecffb7f0e5e0\", \"remoting_unittests\": \"ec54086271172334e5d3ce65b8b84b1102c9375f\", \"sbox_integration_tests\": \"0e90c2db51d53302560a5b2bea277b78ef10f5aa\", \"sbox_unittests\": \"17394e26765ff02b6e2d236156a567d420bfd550\", \"sbox_validation_tests\": \"197f4fd4c9e9f545573a285d97e492ce4b2b6fac\", \"service_manager_unittests\": \"3fd0f33c1d3b26d6b465952eb60839f4ea3f2225\", \"services_unittests\": \"13cd790acee2608dfbc25d17ac810bb107ccc2f3\", \"setup_unittests\": \"f86f12f68dec970b7512e3346966672a21a140b0\", \"shell_dialogs_unittests\": \"10834e4df4149e17fab735be12d583aee09c04e4\", \"skia_unittests\": \"8614469a39fc0b8c48fe8f472495770949ed3018\", \"snapshot_unittests\": \"7f5b204e96955146212f8193ff32804f929f329f\", \"sql_unittests\": \"ade2ee77c78a78e82915fce1921b73da943d7f61\", \"storage_unittests\": \"f390c91448b542cc9e1af3de611c05863d00b629\", \"sync_integration_tests\": \"42e34d500c2b844e9e8932eb7691422656aed57a\", \"traffic_annotation_auditor_unittests\": \"6bea12f828b159e36918d11f465ddf286462d2c6\", \"ui_base_unittests\": \"e2d5a7a07e241450d6e930587430b7ed49371569\", \"ui_touch_selection_unittests\": \"a3be6b9cae560a09f9628d8e0e7eed00f6d2e4d9\", \"unit_tests\": \"0fc8bcd51d8280e181fc5e6a04f0cceb6a3143b5\", \"updater_tests\": \"5e24b7194170bec430ecf7ef0d6c3bc95e4e0645\", \"url_unittests\": \"ac89c3ed54cc2ab9c339ba045d8f4859768e8745\", \"views_unittests\": \"f627409931327d2c1d1b50e9c6a0dfcbb5cb7727\", \"viz_unittests\": \"5c12bba6748fb66a7601ca0348f84202dfefff69\", \"vr_common_unittests\": \"af1878c9a97e9bb66155b6ec6df9bdb33a05676f\", \"vr_pixeltests\": \"cb370df9a8b86ea1e23892ca086794dd27ad5c06\", \"weblayer_browsertests\": \"3ecdcafe3a19118a7b625f22bcc9d08fac40054e\", \"weblayer_unittests\": \"3752293ebb92bea0f3462ed465014be25812f015\", \"wm_unittests\": \"888966f7ca030aaa25d37dc60abb1232e6a0ec57\", \"wtf_unittests\": \"65c26399cbb280b830ea5385ee095d99edff8a5c\", \"zlib_unittests\": \"e1ed58501a75f2b1d0b44e4130ec00a7ea64e52e\", \"zucchini_unittests\": \"a0d8a48a147ee6f87f13a20d6f10aa107bd374a0\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_ba2591630e3c492a7f8bed019e3d41c3ae1f8879793a1bf71c3be1367a2696d6_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-3-n4wf\"], \"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\": [\"5632-2e614e1\"], \"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": "1605540653304005",
"status": "COMPLETED",
"status_changed_ts": "1605544477474786",
"tags": [
"build_address:luci.chromium.ci/win-asan/15669",
"builder:win-asan",
"buildset:commit/git/0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0863ab9515ef5fc111ccdaad5fe18f81b05f6dcb",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9028892388809648176",
"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/8863462324895715104/+/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:4fea7fa13bb2f610",
"user_agent:luci-scheduler"
],
"updated_ts": "1605544477479522",
"url": "https://ci.chromium.org/b/8863462324895715104",
"utcnow_ts": "1605544477956493"
}
}
[16:34:37.971] Invocation finished in 1h3m52.567064986s with status SUCCEEDED