[10:46:54.917] New invocation is queued and will start shortly
[10:46:56.059] Starting the invocation (attempt 1)
[10:46:56.093] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:46:56.093] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:46:56.093] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021300101413639744",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"b68f930eaf6d3e6fb263089a2dc017324615b241\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021300101413639744",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/b68f930eaf6d3e6fb263089a2dc017324615b241",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b68f930eaf6d3e6fb263089a2dc017324615b241",
"gitiles_ref:refs/heads/master"
]
}
[10:46:57.163] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612781216199499",
"id": "8855870039400689840",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b68f930eaf6d3e6fb263089a2dc017324615b241\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612781216888940",
"tags": [
"build_address:luci.chromium.ci/win-asan/17238",
"builder:win-asan",
"buildset:commit/git/b68f930eaf6d3e6fb263089a2dc017324615b241",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b68f930eaf6d3e6fb263089a2dc017324615b241",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021300101413639744",
"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/8855870039400689840/+/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": "1612781216889108",
"url": "https://ci.chromium.org/b/8855870039400689840",
"utcnow_ts": "1612781217155198"
}
}
[10:46:57.163] Task URL: https://ci.chromium.org/b/8855870039400689840
[10:46:57.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:2:0) after 1m0s
[10:46:58.347] Received PubSub notification, asking Buildbucket for the build status
[10:46:58.409] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:57.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:2:0)
[10:47:57.235] Timer tick, asking Buildbucket for the build status
[10:47:57.322] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:57.322] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:4:0) after 1m0s
[10:48:57.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:4:0)
[10:48:57.381] Timer tick, asking Buildbucket for the build status
[10:48:57.563] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:57.563] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:5:0) after 1m0s
[10:49:57.787] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:5:0)
[10:49:57.787] Timer tick, asking Buildbucket for the build status
[10:49:58.023] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:58.023] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:6:0) after 1m0s
[10:50:58.082] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:6:0)
[10:50:58.082] Timer tick, asking Buildbucket for the build status
[10:50:58.629] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:58.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:7:0) after 1m0s
[10:51:58.608] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:7:0)
[10:51:58.608] Timer tick, asking Buildbucket for the build status
[10:51:58.732] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:58.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:8:0) after 1m0s
[10:52:58.817] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:8:0)
[10:52:58.817] Timer tick, asking Buildbucket for the build status
[10:52:58.900] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:58.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:9:0) after 1m0s
[10:53:58.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:9:0)
[10:53:58.929] Timer tick, asking Buildbucket for the build status
[10:53:59.132] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:59.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:10:0) after 1m0s
[10:54:59.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:10:0)
[10:54:59.134] Timer tick, asking Buildbucket for the build status
[10:54:59.337] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:59.337] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:11:0) after 1m0s
[10:55:59.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:11:0)
[10:55:59.353] Timer tick, asking Buildbucket for the build status
[10:55:59.526] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:59.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:12:0) after 1m0s
[10:56:59.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:12:0)
[10:56:59.599] Timer tick, asking Buildbucket for the build status
[10:56:59.702] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:59.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:13:0) after 1m0s
[10:57:59.754] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:13:0)
[10:57:59.754] Timer tick, asking Buildbucket for the build status
[10:57:59.807] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:59.807] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:14:0) after 1m0s
[10:58:59.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:14:0)
[10:58:59.852] Timer tick, asking Buildbucket for the build status
[10:59:00.263] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:00.263] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:15:0) after 1m0s
[11:00:00.572] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:15:0)
[11:00:00.572] Timer tick, asking Buildbucket for the build status
[11:00:00.650] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:00.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:16:0) after 1m0s
[11:01:00.710] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:16:0)
[11:01:00.710] Timer tick, asking Buildbucket for the build status
[11:01:00.880] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:00.880] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:17:0) after 1m0s
[11:02:01.419] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:17:0)
[11:02:01.419] Timer tick, asking Buildbucket for the build status
[11:02:01.557] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:01.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:18:0) after 1m0s
[11:03:01.970] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:18:0)
[11:03:01.970] Timer tick, asking Buildbucket for the build status
[11:03:02.317] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:02.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:19:0) after 1m0s
[11:04:02.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:19:0)
[11:04:02.368] Timer tick, asking Buildbucket for the build status
[11:04:02.654] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:02.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:20:0) after 1m0s
[11:05:02.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:20:0)
[11:05:02.714] Timer tick, asking Buildbucket for the build status
[11:05:03.006] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:03.006] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:21:0) after 1m0s
[11:06:03.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:21:0)
[11:06:03.076] Timer tick, asking Buildbucket for the build status
[11:06:03.211] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:03.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:22:0) after 1m0s
[11:07:03.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:22:0)
[11:07:03.228] Timer tick, asking Buildbucket for the build status
[11:07:03.365] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:03.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:23:0) after 1m0s
[11:08:03.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:23:0)
[11:08:03.387] Timer tick, asking Buildbucket for the build status
[11:08:04.387] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:04.387] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:24:0) after 1m0s
[11:09:04.496] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:24:0)
[11:09:04.496] Timer tick, asking Buildbucket for the build status
[11:09:05.279] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:05.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:25:0) after 1m0s
[11:10:05.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:25:0)
[11:10:05.329] Timer tick, asking Buildbucket for the build status
[11:10:05.524] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:05.524] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:26:0) after 1m0s
[11:11:05.643] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:26:0)
[11:11:05.643] Timer tick, asking Buildbucket for the build status
[11:11:05.787] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:05.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:27:0) after 1m0s
[11:12:06.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:27:0)
[11:12:06.101] Timer tick, asking Buildbucket for the build status
[11:12:06.238] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:06.238] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:28:0) after 1m0s
[11:13:06.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:28:0)
[11:13:06.510] Timer tick, asking Buildbucket for the build status
[11:13:06.698] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:06.698] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:29:0) after 1m0s
[11:14:06.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:29:0)
[11:14:06.711] Timer tick, asking Buildbucket for the build status
[11:14:06.810] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:06.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:30:0) after 1m0s
[11:15:06.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:30:0)
[11:15:06.924] Timer tick, asking Buildbucket for the build status
[11:15:07.234] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:07.234] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:31:0) after 1m0s
[11:16:07.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:31:0)
[11:16:07.326] Timer tick, asking Buildbucket for the build status
[11:16:07.459] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:07.459] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:32:0) after 1m0s
[11:17:07.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:32:0)
[11:17:07.474] Timer tick, asking Buildbucket for the build status
[11:17:07.573] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:07.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:33:0) after 1m0s
[11:18:07.630] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:33:0)
[11:18:07.630] Timer tick, asking Buildbucket for the build status
[11:18:07.697] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:07.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:34:0) after 1m0s
[11:19:07.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:34:0)
[11:19:07.832] Timer tick, asking Buildbucket for the build status
[11:19:08.035] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:08.035] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:35:0) after 1m0s
[11:20:08.120] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:35:0)
[11:20:08.120] Timer tick, asking Buildbucket for the build status
[11:20:08.405] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:08.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:36:0) after 1m0s
[11:21:08.460] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:36:0)
[11:21:08.460] Timer tick, asking Buildbucket for the build status
[11:21:08.724] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:08.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:37:0) after 1m0s
[11:22:08.777] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:37:0)
[11:22:08.777] Timer tick, asking Buildbucket for the build status
[11:22:09.134] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:09.135] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:38:0) after 1m0s
[11:23:09.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:38:0)
[11:23:09.294] Timer tick, asking Buildbucket for the build status
[11:23:09.401] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:09.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:39:0) after 1m0s
[11:24:09.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:39:0)
[11:24:09.424] Timer tick, asking Buildbucket for the build status
[11:24:09.538] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:09.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:40:0) after 1m0s
[11:25:09.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:40:0)
[11:25:09.571] Timer tick, asking Buildbucket for the build status
[11:25:09.852] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:09.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:41:0) after 1m0s
[11:26:09.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:41:0)
[11:26:09.990] Timer tick, asking Buildbucket for the build status
[11:26:10.083] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:10.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:42:0) after 1m0s
[11:27:10.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:42:0)
[11:27:10.148] Timer tick, asking Buildbucket for the build status
[11:27:10.520] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:10.520] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:43:0) after 1m0s
[11:28:10.573] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:43:0)
[11:28:10.573] Timer tick, asking Buildbucket for the build status
[11:28:10.706] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:10.706] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:44:0) after 1m0s
[11:29:10.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:44:0)
[11:29:10.691] Timer tick, asking Buildbucket for the build status
[11:29:11.200] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:11.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:45:0) after 1m0s
[11:30:11.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:45:0)
[11:30:11.257] Timer tick, asking Buildbucket for the build status
[11:30:11.390] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:11.390] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:46:0) after 1m0s
[11:31:11.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:46:0)
[11:31:11.410] Timer tick, asking Buildbucket for the build status
[11:31:11.823] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:11.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:47:0) after 1m0s
[11:32:11.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:47:0)
[11:32:11.897] Timer tick, asking Buildbucket for the build status
[11:32:12.231] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:12.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:48:0) after 1m0s
[11:33:12.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:48:0)
[11:33:12.261] Timer tick, asking Buildbucket for the build status
[11:33:12.393] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:12.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:49:0) after 1m0s
[11:34:12.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:49:0)
[11:34:12.454] Timer tick, asking Buildbucket for the build status
[11:34:12.569] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:12.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:50:0) after 1m0s
[11:35:12.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:50:0)
[11:35:12.752] Timer tick, asking Buildbucket for the build status
[11:35:12.879] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:12.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:51:0) after 1m0s
[11:36:12.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:51:0)
[11:36:12.933] Timer tick, asking Buildbucket for the build status
[11:36:13.184] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:13.184] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:52:0) after 1m0s
[11:37:13.195] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:52:0)
[11:37:13.195] Timer tick, asking Buildbucket for the build status
[11:37:13.431] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:13.431] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:53:0) after 1m0s
[11:38:13.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:53:0)
[11:38:13.449] Timer tick, asking Buildbucket for the build status
[11:38:13.700] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:13.700] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:54:0) after 1m0s
[11:39:13.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:54:0)
[11:39:13.726] Timer tick, asking Buildbucket for the build status
[11:39:13.883] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:13.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:55:0) after 1m0s
[11:40:13.927] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:55:0)
[11:40:13.927] Timer tick, asking Buildbucket for the build status
[11:40:14.013] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:14.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:56:0) after 1m0s
[11:41:14.090] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:56:0)
[11:41:14.090] Timer tick, asking Buildbucket for the build status
[11:41:14.213] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:14.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:57:0) after 1m0s
[11:42:14.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:57:0)
[11:42:14.241] Timer tick, asking Buildbucket for the build status
[11:42:14.417] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:14.417] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:58:0) after 1m0s
[11:43:14.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:58:0)
[11:43:14.786] Timer tick, asking Buildbucket for the build status
[11:43:15.158] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:15.158] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:59:0) after 1m0s
[11:44:15.179] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:59:0)
[11:44:15.179] Timer tick, asking Buildbucket for the build status
[11:44:15.251] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:15.252] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:60:0) after 1m0s
[11:45:15.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:60:0)
[11:45:15.270] Timer tick, asking Buildbucket for the build status
[11:45:15.346] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:15.346] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:61:0) after 1m0s
[11:46:15.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:61:0)
[11:46:15.333] Timer tick, asking Buildbucket for the build status
[11:46:15.988] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:46:15.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:62:0) after 1m0s
[11:47:16.035] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:62:0)
[11:47:16.035] Timer tick, asking Buildbucket for the build status
[11:47:16.541] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:47:16.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:63:0) after 1m0s
[11:48:16.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:63:0)
[11:48:16.566] Timer tick, asking Buildbucket for the build status
[11:48:16.677] Build 8855870039400689840: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:48:16.677] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021300101413639744:64:0) after 1m0s
[11:48:18.736] Received PubSub notification, asking Buildbucket for the build status
[11:48:19.507] Build 8855870039400689840: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:48:19.507] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612784897004932",
"created_by": "project:chromium",
"created_ts": "1612781216199499",
"id": "8855870039400689840",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"b68f930eaf6d3e6fb263089a2dc017324615b241\"}}",
"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\": \"b68f930eaf6d3e6fb263089a2dc017324615b241\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17238, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"5712107a35b053c2e4ffb3a5690e6e482a0c84f5\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"b68f930eaf6d3e6fb263089a2dc017324615b241\", \"got_revision_cp\": \"refs/heads/master@{#851648}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"462378addab907ab7452d38baccb27fe2ef7289a\", \"got_v8_revision_cp\": \"refs/heads/9.0.138@{#1}\", \"got_webrtc_revision\": \"69c0118c5139a1a6b431d2f3f812dc9ed6af6b74\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33186}\", \"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\": \"b68f930eaf6d3e6fb263089a2dc017324615b241\", \"swarm_hashes_refs/heads/master(at){#851648}_without_patch\": {\"absl_hardening_tests\": \"e923a172c088f98e05d18ec38e1f1a09870cd82b\", \"accessibility_unittests\": \"9f2b614999f68a8194ed57d7e5ba6c0acb96e2f0\", \"angle_unittests\": \"ea269b5dc40493afed29d2cb256da128c757e89f\", \"app_shell_unittests\": \"b0c645142217d690760040d89437b0588b68eb93\", \"aura_unittests\": \"c38fb13bb874585c6e47bc660332d21f9cff31d4\", \"base_unittests\": \"79379d83c80c96542829edb7bfd9832a5f8968c1\", \"base_util_unittests\": \"cac3139eb4c7310596545a872b20b3535465153c\", \"blink_common_unittests\": \"f6cfcd791ed8c7e70f54de1ea4a7b0f6ac357d2e\", \"blink_fuzzer_unittests\": \"18778bfcb6e53bf7ca110d02427a037d32ced5c2\", \"blink_heap_unittests\": \"c3164a2cf7ec2e9c488850df7b38efce49b90533\", \"blink_platform_unittests\": \"5f0c056fa8cb093982e683b2a31e41a5b3634f10\", \"blink_unittests\": \"beb43c69526c3cfd51b2828226cf68f9f4089bd0\", \"boringssl_crypto_tests\": \"2ce249d500b147cb8b3383d194860d56c98980e5\", \"boringssl_ssl_tests\": \"285463a5fbab12512d318753623a9364b24cc624\", \"browser_switcher_bho_unittests\": \"b0e86d55a229bc47f2f00fdc763151c979864031\", \"browser_tests\": \"5d9218bccb3271b6bf9b48062b771defef50604c\", \"capture_unittests\": \"5b6e518a92c8a15247bfec451ca1f1b569387bfc\", \"cast_unittests\": \"b4a2cbb050fb8f2076bbd89b44c5bdc3f2006f71\", \"cc_unittests\": \"8ff7fc9ab15230a9b3ced8073be474177e829ff3\", \"chrome_app_unittests\": \"bbab2b42f3b9eb7286d56d805adaff99d67eb315\", \"chrome_cleaner_unittests\": \"16d15e1d02d791f385a8fc0d5f7ee8e3bb7fa731\", \"chrome_elf_unittests\": \"8364c4fbda9df4eaf3f5378f2060cf91f233674f\", \"chromedriver_unittests\": \"4e4dbd5edf21fce28d416c162fd2aeb8c086a3bb\", \"components_browsertests\": \"ae10c3f8dc7afb74db3d1d29009b8065772ee234\", \"components_unittests\": \"8378348345bd6f9f4696f64410a98f3a6b781216\", \"compositor_unittests\": \"c604a70896358701563fd7c72befacf1bc09336d\", \"content_browsertests\": \"8bdc60aa291959b9f2fc09125871d579b56307bc\", \"content_unittests\": \"1698363fbf790346288c8da3fbaa948dc57f1243\", \"courgette_unittests\": \"227317c338ac853b0db91a6712b18156ca99beef\", \"crashpad_tests\": \"659ce6a3aa3f7bd13949413748e068bee8f094a7\", \"cronet_tests\": \"e48cdce59179f1c7b4debf2f379ed52de213d5cb\", \"cronet_unittests\": \"7c741bd6691e07cd6495d426cc0c1ad5fa1c04f6\", \"crypto_unittests\": \"019ce5214aeac7b9ac33888c8e1feff043d7a407\", \"delayloads_unittests\": \"90e7db8b58fecbd2b312b1985c47b7fcebdd1218\", \"device_unittests\": \"73d0de439ac1c1480ef83803e58883a93bc5dd52\", \"display_unittests\": \"c856634c238cf2cb4e6b16f0056489c584a97295\", \"elevation_service_unittests\": \"bcbb06eeee6f8807ee5cc30c6474039f8d350165\", \"events_unittests\": \"2d1741c3baccbf7956c25a27325138d3f410647a\", \"extensions_browsertests\": \"420d034b913c76cd5fe3aab08e880ce724f4dbe2\", \"extensions_unittests\": \"f917a064c3b0d8b3761eca3235f2b4e1ae63d419\", \"filesystem_service_unittests\": \"f5203684e6a5572a42b80d920341efd6bd7cb6fa\", \"gcm_unit_tests\": \"9a2b3cc714aa032a00f8444ac8b3501478df2574\", \"gcp_unittests\": \"0bad8d1b1b0003244923e14508a08adb2cc0f8d6\", \"gfx_unittests\": \"0c5c9caa8f228861b2563c5f69efcd0bf43d02af\", \"gin_unittests\": \"3f1f57455883b9d84012ff738be54a8e942e5050\", \"google_apis_unittests\": \"1c8457e6286f52f02b863d37ec6821cccd0c3761\", \"gpu_unittests\": \"8a1503c1f4fc86fb7466ccc00cc8144ef5ed8419\", \"gwp_asan_unittests\": \"c3e390a489c6b38809020c26229665d2f4d1e4b6\", \"headless_browsertests\": \"a7ce2fb2bde91a847b56482d568addc720fffad0\", \"headless_unittests\": \"1680ff1eaf51529a14c53225845700c2006b3e0e\", \"install_static_unittests\": \"fa0be9393cf2b60d06941a4e3147a34b2ae9bc0d\", \"installer_util_unittests\": \"c98f636bc84e6c1cf252731ebd304635fe834bb3\", \"interactive_ui_tests\": \"92a28b7181c0a43b25627024d9f9a50c8fc77935\", \"ipc_tests\": \"979222984a13ac3caed867431102b59bdc3c4365\", \"jingle_unittests\": \"83f9e9b3177a560e6e4e530b622910e53baa0b4c\", \"latency_unittests\": \"cc6a1249570d04ac4002c23c0f0148295ad37a8b\", \"libjingle_xmpp_unittests\": \"e5a88047c4a5809b812962c6d79bcbc7bb9a0e1b\", \"liburlpattern_unittests\": \"1e38d5b7f267e3ca5b11688cd8ab3152c7d9bee0\", \"media_blink_unittests\": \"53bde480c0ce8d03525ae929b111cb52c0d12104\", \"media_unittests\": \"327484ef4e692c43158e1337c138db98f2241402\", \"message_center_unittests\": \"81ade81ab23ef01d42efe9a4a6f238ad90c5ebd4\", \"midi_unittests\": \"07fc535839e9fcd4a579240f63dc0d446b55836b\", \"mojo_core_unittests\": \"61a9f0b3426be0e1622a2433fa95a2be2b3f5c49\", \"mojo_unittests\": \"717c41edb7407ad2ae8edb42995145bc7003613f\", \"nacl_loader_unittests\": \"afc605f27fe838b4373963cf2651c0fcc6178241\", \"native_theme_unittests\": \"12ea67b9ba5b38821435430f23e18167718ef17e\", \"net_unittests\": \"0f82ebd3fd034069e00b0cf8d20127e659dd3c0a\", \"notification_helper_unittests\": \"eb7bfb55a4ea21f4fa3dfd81dddc9b86fe7dc208\", \"pdf_unittests\": \"8bf4fde9eb5fc2491500aa3fe29301fa88533c22\", \"perfetto_unittests\": \"a006052ed55b90510625c6629b5c6c6eebf448ff\", \"ppapi_unittests\": \"bfeef18ac05eb674dd34bf4e5ce8971986503922\", \"printing_unittests\": \"29ea15c751d8c06d9aae0351f2cffa59d451c7dc\", \"remoting_unittests\": \"5f84fb73000a4a0faf60ad558eb5d2b14e8b47cc\", \"sbox_integration_tests\": \"7834297f0b2125a54210a55c9f289e85925a8f01\", \"sbox_unittests\": \"c3561d031d353db6e4aba1992cb3d5f48902aa64\", \"sbox_validation_tests\": \"9e1cc4ffee760e39ebd3273281939f4c71336314\", \"service_manager_unittests\": \"760bc4ab86c7e13c7e6f36200524060862767da9\", \"services_unittests\": \"a02807251cc7c2f0e6daaf37b6ce9e2d25c51dfa\", \"setup_unittests\": \"4ab8d5a46f30086cfd5834ebb8d3120ec12bd340\", \"shell_dialogs_unittests\": \"d1de4bc3a85877d0d52d9730e14eb2887309fd6c\", \"skia_unittests\": \"59c827e281c639ccac26a6aae1c8062d454a528b\", \"snapshot_unittests\": \"5f7be5f7371f98d3185035f8b8a76314599cb961\", \"sql_unittests\": \"79bf7f461d34af2644e12f3f91df634c6153adc1\", \"storage_unittests\": \"81faca47c808ca5a73d83430da719d5bedf86906\", \"sync_integration_tests\": \"ab86024e5060e6e10304da7d0414b2a87cd38d8b\", \"traffic_annotation_auditor_unittests\": \"9cf4231e23378d54a7c92e87c281b69687f92b1c\", \"ui_base_unittests\": \"1305ace3a735657683eacd77725e170b14d9ed36\", \"ui_touch_selection_unittests\": \"daa49c279996084c12f28c0739d7f4aeedaea2ae\", \"unit_tests\": \"0b93bd8960997b9954ffc70361130dd4ee5bebb2\", \"updater_tests\": \"c23fffabace434027a5109db8fffdf7c6697e230\", \"url_unittests\": \"3d339b31ceceaab22834c71d3a9ff58b6b1096c7\", \"views_unittests\": \"36e8773d252eb120a09fdce3a25c7766ffcde453\", \"viz_unittests\": \"be606e2665511fcee00a93aff55592d1d325b2a2\", \"vr_common_unittests\": \"3bdc141a11de667c6152583e86b3f5f0fd34b626\", \"vr_pixeltests\": \"b319226a7ac6cb23c49d5ea7b816fdc68abcefcb\", \"weblayer_browsertests\": \"9600d0235329d3c34a395af37ae5e30a305e0dc7\", \"weblayer_unittests\": \"78bfe6e696153534f80c406f66ac9808e551c2f2\", \"wm_unittests\": \"f51ceddecda76e5f3fb6cc036d0b39ed0003e7a8\", \"wtf_unittests\": \"7eab16218bb63ad5ff13a2df1bf1003a61a11220\", \"zlib_unittests\": \"900318e66f2280297884e1cf9aebe09bf35a6c96\", \"zucchini_unittests\": \"d2c53134b45b4fe241590a278f97769626ed653f\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_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-7-1hzs\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"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\": [\"5681-01e7fd1\"], \"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": "1612781217776578",
"status": "COMPLETED",
"status_changed_ts": "1612784897554108",
"tags": [
"build_address:luci.chromium.ci/win-asan/17238",
"builder:win-asan",
"buildset:commit/git/b68f930eaf6d3e6fb263089a2dc017324615b241",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b68f930eaf6d3e6fb263089a2dc017324615b241",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021300101413639744",
"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/8855870039400689840/+/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:519a11edb043f910",
"user_agent:luci-scheduler"
],
"updated_ts": "1612784897587192",
"url": "https://ci.chromium.org/b/8855870039400689840",
"utcnow_ts": "1612784899392102"
}
}
[11:48:19.507] Invocation finished in 1h1m24.610703054s with status SUCCEEDED