[21:37:42.432] New invocation is queued and will start shortly
[21:37:43.534] Starting the invocation (attempt 1)
[21:37:43.569] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:37:43.569] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:37:43.569] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9019266023891978432",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"0a082475fac5d8b8e1b40fa65b434f253fdcf509\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9019266023891978432",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/0a082475fac5d8b8e1b40fa65b434f253fdcf509",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a082475fac5d8b8e1b40fa65b434f253fdcf509",
"gitiles_ref:refs/heads/master"
]
}
[21:37:44.075] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1614721063647595",
"id": "8853835961923688896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a082475fac5d8b8e1b40fa65b434f253fdcf509\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1614721063972952",
"tags": [
"build_address:luci.chromium.ci/win-asan/17598",
"builder:win-asan",
"buildset:commit/git/0a082475fac5d8b8e1b40fa65b434f253fdcf509",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a082475fac5d8b8e1b40fa65b434f253fdcf509",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019266023891978432",
"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/8853835961923688896/+/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": "1614721063973058",
"url": "https://ci.chromium.org/b/8853835961923688896",
"utcnow_ts": "1614721064068799"
}
}
[21:37:44.075] Task URL: https://ci.chromium.org/b/8853835961923688896
[21:37:44.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:2:0) after 1m0s
[21:37:47.697] Received PubSub notification, asking Buildbucket for the build status
[21:37:47.946] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:44.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:2:0)
[21:38:44.076] Timer tick, asking Buildbucket for the build status
[21:38:44.163] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:44.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:4:0) after 1m0s
[21:39:44.150] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:4:0)
[21:39:44.150] Timer tick, asking Buildbucket for the build status
[21:39:44.275] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:44.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:5:0) after 1m0s
[21:40:44.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:5:0)
[21:40:44.291] Timer tick, asking Buildbucket for the build status
[21:40:44.373] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:44.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:6:0) after 1m0s
[21:41:44.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:6:0)
[21:41:44.386] Timer tick, asking Buildbucket for the build status
[21:41:44.454] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:44.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:7:0) after 1m0s
[21:42:44.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:7:0)
[21:42:44.449] Timer tick, asking Buildbucket for the build status
[21:42:44.508] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:44.508] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:8:0) after 1m0s
[21:43:44.524] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:8:0)
[21:43:44.524] Timer tick, asking Buildbucket for the build status
[21:43:44.650] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:44.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:9:0) after 1m0s
[21:44:44.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:9:0)
[21:44:44.711] Timer tick, asking Buildbucket for the build status
[21:44:44.789] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:44.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:10:0) after 1m0s
[21:45:44.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:10:0)
[21:45:44.816] Timer tick, asking Buildbucket for the build status
[21:45:45.025] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:45.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:11:0) after 1m0s
[21:46:45.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:11:0)
[21:46:45.105] Timer tick, asking Buildbucket for the build status
[21:46:45.179] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:45.179] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:12:0) after 1m0s
[21:47:45.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:12:0)
[21:47:45.215] Timer tick, asking Buildbucket for the build status
[21:47:45.405] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:45.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:13:0) after 1m0s
[21:48:45.409] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:13:0)
[21:48:45.409] Timer tick, asking Buildbucket for the build status
[21:48:45.500] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:45.500] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:14:0) after 1m0s
[21:49:45.516] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:14:0)
[21:49:45.516] Timer tick, asking Buildbucket for the build status
[21:49:45.728] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:45.728] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:15:0) after 1m0s
[21:50:45.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:15:0)
[21:50:45.773] Timer tick, asking Buildbucket for the build status
[21:50:45.842] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:45.842] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:16:0) after 1m0s
[21:51:46.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:16:0)
[21:51:46.234] Timer tick, asking Buildbucket for the build status
[21:51:46.317] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:46.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:17:0) after 1m0s
[21:52:46.336] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:17:0)
[21:52:46.336] Timer tick, asking Buildbucket for the build status
[21:52:46.605] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:46.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:18:0) after 1m0s
[21:53:46.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:18:0)
[21:53:46.660] Timer tick, asking Buildbucket for the build status
[21:53:46.763] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:46.763] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:19:0) after 1m0s
[21:54:47.100] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:19:0)
[21:54:47.100] Timer tick, asking Buildbucket for the build status
[21:54:47.189] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:47.189] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:20:0) after 1m0s
[21:55:48.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:20:0)
[21:55:48.165] Timer tick, asking Buildbucket for the build status
[21:55:48.239] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:48.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:21:0) after 1m0s
[21:56:48.372] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:21:0)
[21:56:48.372] Timer tick, asking Buildbucket for the build status
[21:56:48.438] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:48.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:22:0) after 1m0s
[21:57:48.574] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:22:0)
[21:57:48.574] Timer tick, asking Buildbucket for the build status
[21:57:48.670] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:48.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:23:0) after 1m0s
[21:58:48.994] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:23:0)
[21:58:48.994] Timer tick, asking Buildbucket for the build status
[21:58:49.227] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:49.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:24:0) after 1m0s
[21:59:49.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:24:0)
[21:59:49.276] Timer tick, asking Buildbucket for the build status
[21:59:49.340] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:49.340] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:25:0) after 1m0s
[22:00:49.380] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:25:0)
[22:00:49.380] Timer tick, asking Buildbucket for the build status
[22:00:49.467] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:49.467] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:26:0) after 1m0s
[22:01:49.543] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:26:0)
[22:01:49.543] Timer tick, asking Buildbucket for the build status
[22:01:49.932] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:49.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:27:0) after 1m0s
[22:02:50.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:27:0)
[22:02:50.046] Timer tick, asking Buildbucket for the build status
[22:02:50.197] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:50.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:28:0) after 1m0s
[22:03:50.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:28:0)
[22:03:50.253] Timer tick, asking Buildbucket for the build status
[22:03:50.348] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:50.348] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:29:0) after 1m0s
[22:04:50.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:29:0)
[22:04:50.355] Timer tick, asking Buildbucket for the build status
[22:04:50.998] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:50.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:30:0) after 1m0s
[22:05:51.016] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:30:0)
[22:05:51.016] Timer tick, asking Buildbucket for the build status
[22:05:51.213] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:51.213] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:31:0) after 1m0s
[22:06:51.258] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:31:0)
[22:06:51.258] Timer tick, asking Buildbucket for the build status
[22:06:51.331] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:51.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:32:0) after 1m0s
[22:07:51.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:32:0)
[22:07:51.406] Timer tick, asking Buildbucket for the build status
[22:07:51.633] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:51.633] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:33:0) after 1m0s
[22:08:51.671] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:33:0)
[22:08:51.671] Timer tick, asking Buildbucket for the build status
[22:08:51.778] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:51.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:34:0) after 1m0s
[22:09:51.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:34:0)
[22:09:51.820] Timer tick, asking Buildbucket for the build status
[22:09:51.907] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:51.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:35:0) after 1m0s
[22:10:51.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:35:0)
[22:10:51.979] Timer tick, asking Buildbucket for the build status
[22:10:52.058] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:52.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:36:0) after 1m0s
[22:11:52.088] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:36:0)
[22:11:52.088] Timer tick, asking Buildbucket for the build status
[22:11:52.280] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:52.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:37:0) after 1m0s
[22:12:52.299] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:37:0)
[22:12:52.299] Timer tick, asking Buildbucket for the build status
[22:12:52.399] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:52.399] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:38:0) after 1m0s
[22:13:52.379] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:38:0)
[22:13:52.379] Timer tick, asking Buildbucket for the build status
[22:13:52.747] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:52.747] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:39:0) after 1m0s
[22:14:52.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:39:0)
[22:14:52.825] Timer tick, asking Buildbucket for the build status
[22:14:52.965] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:52.965] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:40:0) after 1m0s
[22:15:52.992] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:40:0)
[22:15:52.992] Timer tick, asking Buildbucket for the build status
[22:15:53.098] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:53.098] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:41:0) after 1m0s
[22:16:53.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:41:0)
[22:16:53.124] Timer tick, asking Buildbucket for the build status
[22:16:53.192] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:53.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:42:0) after 1m0s
[22:17:53.303] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:42:0)
[22:17:53.303] Timer tick, asking Buildbucket for the build status
[22:17:53.472] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:53.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:43:0) after 1m0s
[22:18:53.561] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:43:0)
[22:18:53.561] Timer tick, asking Buildbucket for the build status
[22:18:53.650] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:53.650] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:44:0) after 1m0s
[22:19:53.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:44:0)
[22:19:53.618] Timer tick, asking Buildbucket for the build status
[22:19:53.720] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:53.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:45:0) after 1m0s
[22:20:53.743] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:45:0)
[22:20:53.743] Timer tick, asking Buildbucket for the build status
[22:20:53.815] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:53.815] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:46:0) after 1m0s
[22:21:53.902] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:46:0)
[22:21:53.902] Timer tick, asking Buildbucket for the build status
[22:21:53.988] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:53.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:47:0) after 1m0s
[22:22:54.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:47:0)
[22:22:54.036] Timer tick, asking Buildbucket for the build status
[22:22:54.148] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:54.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:48:0) after 1m0s
[22:23:54.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:48:0)
[22:23:54.285] Timer tick, asking Buildbucket for the build status
[22:23:54.438] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:54.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:49:0) after 1m0s
[22:24:54.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:49:0)
[22:24:54.497] Timer tick, asking Buildbucket for the build status
[22:24:54.627] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:54.627] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:50:0) after 1m0s
[22:25:54.810] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:50:0)
[22:25:54.810] Timer tick, asking Buildbucket for the build status
[22:25:54.906] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:54.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:51:0) after 1m0s
[22:26:54.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:51:0)
[22:26:54.997] Timer tick, asking Buildbucket for the build status
[22:26:55.074] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:55.074] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:52:0) after 1m0s
[22:27:55.103] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:52:0)
[22:27:55.104] Timer tick, asking Buildbucket for the build status
[22:27:55.212] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:55.212] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:53:0) after 1m0s
[22:28:55.284] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:53:0)
[22:28:55.284] Timer tick, asking Buildbucket for the build status
[22:28:55.373] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:55.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:54:0) after 1m0s
[22:29:55.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:54:0)
[22:29:55.390] Timer tick, asking Buildbucket for the build status
[22:29:56.020] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:56.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:55:0) after 1m0s
[22:30:56.053] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:55:0)
[22:30:56.053] Timer tick, asking Buildbucket for the build status
[22:30:56.621] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:56.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:56:0) after 1m0s
[22:31:56.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:56:0)
[22:31:56.670] Timer tick, asking Buildbucket for the build status
[22:31:56.871] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:56.871] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:57:0) after 1m0s
[22:32:56.935] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:57:0)
[22:32:56.935] Timer tick, asking Buildbucket for the build status
[22:32:57.220] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:57.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:58:0) after 1m0s
[22:33:57.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:58:0)
[22:33:57.314] Timer tick, asking Buildbucket for the build status
[22:33:57.447] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:57.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:59:0) after 1m0s
[22:34:57.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:59:0)
[22:34:57.451] Timer tick, asking Buildbucket for the build status
[22:34:57.552] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:57.552] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:60:0) after 1m0s
[22:35:58.369] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:60:0)
[22:35:58.369] Timer tick, asking Buildbucket for the build status
[22:35:58.478] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:35:58.478] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:61:0) after 1m0s
[22:36:58.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:61:0)
[22:36:58.559] Timer tick, asking Buildbucket for the build status
[22:36:58.638] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:36:58.638] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:62:0) after 1m0s
[22:37:58.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:62:0)
[22:37:58.676] Timer tick, asking Buildbucket for the build status
[22:37:58.783] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:37:58.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:63:0) after 1m0s
[22:38:58.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:63:0)
[22:38:58.801] Timer tick, asking Buildbucket for the build status
[22:38:58.870] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:38:58.870] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:64:0) after 1m0s
[22:39:59.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:64:0)
[22:39:59.059] Timer tick, asking Buildbucket for the build status
[22:39:59.175] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:39:59.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:65:0) after 1m0s
[22:40:59.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:65:0)
[22:40:59.264] Timer tick, asking Buildbucket for the build status
[22:40:59.351] Build 8853835961923688896: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:40:59.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9019266023891978432:66:0) after 1m0s
[22:41:12.642] Received PubSub notification, asking Buildbucket for the build status
[22:41:13.641] Build 8853835961923688896: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:41:13.641] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1614724871445870",
"created_by": "project:chromium",
"created_ts": "1614721063647595",
"id": "8853835961923688896",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"0a082475fac5d8b8e1b40fa65b434f253fdcf509\"}}",
"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\": \"0a082475fac5d8b8e1b40fa65b434f253fdcf509\", \"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-1-qhp0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17598, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-3\", \"got_angle_revision\": \"87b1875ca78afe9bf258287c5b8848e45df7dc10\", \"got_dawn_revision\": \"29f0bd7e6ac26992ecd380b9dbdc91e6fc9646c8\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"0a082475fac5d8b8e1b40fa65b434f253fdcf509\", \"got_revision_cp\": \"refs/heads/master@{#859133}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"106e01583a3ac2fb950777390dde2dcc31a8f161\", \"got_v8_revision\": \"304242f4421e5e8b06daacad70d18e8bac6f5af6\", \"got_v8_revision_cp\": \"refs/heads/9.1.15@{#1}\", \"got_webrtc_revision\": \"07d83c8a9af02a0afc473503f75f150f23e3e0ff\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33366}\", \"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\": \"0a082475fac5d8b8e1b40fa65b434f253fdcf509\", \"swarm_hashes_refs/heads/master(at){#859133}_without_patch\": {\"absl_hardening_tests\": \"a63afbbd6c57597a48ced6e2757d4c40892f935e\", \"accessibility_unittests\": \"5f8b8634cda4d56d466a84c5a79a33a9917a4cc4\", \"angle_unittests\": \"34e78356aa9afdcf79651c43c81b6e1a10b4aaa9\", \"app_shell_unittests\": \"16eed5c09bfe8d2b9bb61ed496fede02bdc69292\", \"aura_unittests\": \"6a5edfb0cdaf1e4960dbf6b4a3d760ef3f44335f\", \"base_unittests\": \"b0108a0e35bccadde3b948f359169bfaef3401f8\", \"base_util_unittests\": \"8e9df1d08a922afcf42a89b92466616882b5f122\", \"blink_common_unittests\": \"1735b61dda229366b11bf3aecfdd7370002ac328\", \"blink_fuzzer_unittests\": \"18ab428b480b0c68b85485d78746f3c5f171dc76\", \"blink_heap_unittests\": \"02d829943dd9c2c2fa4fadae592f615537aa60c2\", \"blink_platform_unittests\": \"91e21710b0d85ae6b30fe7ba346b5a09c7bba531\", \"blink_unittests\": \"3d8d8e2fc816961e5f97b4df298b7f5029f3940a\", \"boringssl_crypto_tests\": \"34d34a8a243ee79e8fe25055262a586d1f2d8a9e\", \"boringssl_ssl_tests\": \"ed7c7ca87a3519412a539f2c9e998f5931c6a241\", \"browser_switcher_bho_unittests\": \"ee6bb8a69925e2be8b9442df20bb8cec674f4fdd\", \"browser_tests\": \"6c8a521a7e5c99729e9bf7820427cd19c0043df7\", \"capture_unittests\": \"969c635878255b8b2270dbeace393c9417aa2730\", \"cast_unittests\": \"b9731b752b79ccd9988d0824b10d4ee87b63ae09\", \"cc_unittests\": \"92d9f07f1d584ea5706dbfddf14fba3cca5375c4\", \"chrome_app_unittests\": \"6bdf2552c3f6c6a5e442a7fc699abc4da531a3bc\", \"chrome_cleaner_unittests\": \"8953642e5b88de0eb1bd7c1ff7309f90dcf3e003\", \"chrome_elf_unittests\": \"75e4239f49f9186e3ba74f4a1d207461fe55f652\", \"chromedriver_unittests\": \"420f02a0c557916555b1a8a1c928c732543410b7\", \"color_unittests\": \"2ee6ef588e3ccb771af0aac71395a6a2009c6edf\", \"components_browsertests\": \"159f6bc5da1de4d5de3401ccb34398e06fce84e4\", \"components_unittests\": \"dee213ff7c3891084bc4cd6899a0c0d6646cd375\", \"compositor_unittests\": \"f766bfb9355e2dd1ac1e6351f8938e8ebf1509b7\", \"content_browsertests\": \"10bb96077193412e330c97a41fb5cbcd104a3927\", \"content_unittests\": \"0af06a39f11b87388f230d6378a6a411cb25ddd5\", \"courgette_unittests\": \"716705507aef56a1557bb66ceed90c6ee9c1a531\", \"crashpad_tests\": \"4c4b5427419b04022671d258db15182c8a2ad49f\", \"cronet_tests\": \"6ef939f402e6051143636c401a3e8159c131a6d3\", \"cronet_unittests\": \"9aaff006771e6bc14ac4410c132c2d3a4899e18e\", \"crypto_unittests\": \"975b5b28623dbf184ba3b436d8fa522b574c6c57\", \"delayloads_unittests\": \"74fb7c6b95892eee95631a487a280b9767962e0b\", \"device_unittests\": \"a54144bfdf9fb9df0d5b0ade5e12796b2cafeff1\", \"display_unittests\": \"4e3d5b697d82c2788dc3371b0facc9b6eb85b09d\", \"elevation_service_unittests\": \"c9ce8936dc88fd1fa839168bd5e04b9ed9f52688\", \"events_unittests\": \"16058762deed229dfefe6a023cde83b12e03fa41\", \"extensions_browsertests\": \"2d49bbfaec28648906897b7d73dfc58191874d44\", \"extensions_unittests\": \"eeb49578c87410515a2fc1977d80bc90175ecc0a\", \"filesystem_service_unittests\": \"0d1c25f73254cc91b7b2ab5ddfb56a4d0a48c1e1\", \"gcm_unit_tests\": \"e0b8397248305f779910c58bc423c9f559edccbf\", \"gcp_unittests\": \"6b6415a7a49d985e47a8feeebc097d881ba90397\", \"gfx_unittests\": \"841bc5056399b468cfc37be8dea205df16b42374\", \"gin_unittests\": \"8119ecaaaece5488d915ccd9906f7f13db39cdd8\", \"google_apis_unittests\": \"d7b79027dee0f800f96f63638738cbdc95302823\", \"gpu_unittests\": \"96ae8e7726d84f91395d42925a09c4df86947fab\", \"gwp_asan_unittests\": \"038e4b712ec2d114e2329a02bfc298abe99c0336\", \"headless_browsertests\": \"41da05ac8e18194084323c918f374b788e148617\", \"headless_unittests\": \"5f80a27baa02319fe86c374c1f31638618b8757e\", \"install_static_unittests\": \"e41b73dca97fb0e39ea4811b029fc33d96263722\", \"installer_util_unittests\": \"a5095eebe8891e8568acfe6a085d14efddf35cc2\", \"interactive_ui_tests\": \"c7831c42eec598ad76ed4240b2a1bde9cee28013\", \"ipc_tests\": \"6002a1ad9958146247615068f33ec8511ce749f6\", \"jingle_unittests\": \"71bf8662346a2723a21c0cbba6231da1c6b1dab4\", \"latency_unittests\": \"9d2dff6cc153b2e979c946722c6ad0e0485201af\", \"libjingle_xmpp_unittests\": \"f0f2e8a30e47ef19c3072749e0bbf1706316a32e\", \"liburlpattern_unittests\": \"141378f81166c17213bf0d1a30199d8ab57ba6d6\", \"media_blink_unittests\": \"ff9e0e0369578121361b83ccbc98d1242e170873\", \"media_unittests\": \"adc3f67ab57c24f7c99a46f1db928d0c556406e3\", \"message_center_unittests\": \"f3f19ddc11e28887a55f8c722482a0314d018d02\", \"midi_unittests\": \"7dc65f6158d503a3b51c511664e93a9586031002\", \"mojo_core_unittests\": \"047b3bd9c0dd7713e57d5715aed70ef7005bff3f\", \"mojo_unittests\": \"8ff92ee13944d5ec875f52cf2a8e568d8249e259\", \"nacl_loader_unittests\": \"26dc094f191a2bac21c80fe9deb5fd168cbf15c2\", \"native_theme_unittests\": \"1ab3da0a5b28723c222e5a40d4ca0013f84c5047\", \"net_unittests\": \"f0f611ba081f71dda49cb37ff814d726c33e74da\", \"notification_helper_unittests\": \"e0bd6ee885a6ac3aadfbc564456df292b3969a90\", \"pdf_unittests\": \"929a06c246284d653f1c5b6a36ece83223a282fd\", \"perfetto_unittests\": \"44f9917c6011d135949b7f9fd14b9c324a5b257c\", \"ppapi_unittests\": \"bf34ddf6b737a829f250bac4389afd9e83a09d7d\", \"printing_unittests\": \"571eaee2b48d4e8be782288be4b7c0e910462b38\", \"remoting_unittests\": \"19fd94bcfb9e038e130542586e1b642090e4bf7e\", \"sbox_integration_tests\": \"9ec17ea243b32741d0def3f0038640637e0ea1b2\", \"sbox_unittests\": \"a45d345f6406317cb8ae980519a11405d1a76744\", \"sbox_validation_tests\": \"07242b204dbace8f3eec03ff236f07d0e0bab5cd\", \"service_manager_unittests\": \"05144fccfd91578980dedb9c46c4aaf2571285df\", \"services_unittests\": \"d9530198237bcd7924187b0f0a73519ca4fab969\", \"setup_unittests\": \"efe0ea47a0a904b8deeb15bbdea3a6aac061b7cb\", \"shell_dialogs_unittests\": \"a77e42d209e11938b4c8bc7afd17df25de81406d\", \"skia_unittests\": \"a6c698690758d1ea1fae30b7dd8f866fd63fc769\", \"snapshot_unittests\": \"12dc6274194a01d2c49ae7da8ae883d95267eee3\", \"sql_unittests\": \"02415c4494eb2819f0d3edc9bc00ee8cf9da452a\", \"storage_unittests\": \"bf6926b6840ba20b23baff2e6726c0142eb43c60\", \"sync_integration_tests\": \"95ea0e051b20e392cbbee345f51c74dfb490396b\", \"traffic_annotation_auditor_unittests\": \"6b9f4d3c04da26d0ee20aef60e8bea7e91d264e6\", \"ui_base_unittests\": \"1f30cb2f96eb739c8bd0fc2490f8f4854bdc8935\", \"ui_touch_selection_unittests\": \"d126200e182971bf3f2388eebd1cd533c5dd0d1d\", \"unit_tests\": \"aed3447e7a9d8639a832f144e20848ae620d9f68\", \"updater_tests\": \"bf81af4f95834ee00cdfd5a5c692cbffa7d78336\", \"url_unittests\": \"bb6c55bdf0d5ce08b676906676074188cdcea7e1\", \"views_unittests\": \"1eb26fc4f135490ea0055b9ec3678b7c09910c2c\", \"viz_unittests\": \"cab904a43b10145afc829923b315bf374a012362\", \"vr_common_unittests\": \"075fc511ae9bd34e9f646ddf53ddc5d1f91f0019\", \"vr_pixeltests\": \"86f3e266ae3b03c949551cccd618b5dbaa1996b8\", \"weblayer_browsertests\": \"fe5fc304af782fba2ec4f73cafd32975a0e0a4b7\", \"weblayer_unittests\": \"3755451ce5e6ce9bcc8865f9478cca1065dd1a95\", \"wm_unittests\": \"2f18720832db1abb4f20301745e385b2c07f234c\", \"wtf_unittests\": \"ecd0ba053ac406252db841387f30a820c46cbc3e\", \"zlib_unittests\": \"ceb5289f32d45497a5290cb78428882d1edd7295\", \"zucchini_unittests\": \"879ff00ef07076ea404d9ebbd6ebdfbb40a05d6a\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_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-1-qhp0\"], \"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\": [\"5725-1a0d85e\"], \"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": "1614721067302836",
"status": "COMPLETED",
"status_changed_ts": "1614724872157062",
"tags": [
"build_address:luci.chromium.ci/win-asan/17598",
"builder:win-asan",
"buildset:commit/git/0a082475fac5d8b8e1b40fa65b434f253fdcf509",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0a082475fac5d8b8e1b40fa65b434f253fdcf509",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9019266023891978432",
"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/8853835961923688896/+/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:520db1a50beb9d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1614724872163042",
"url": "https://ci.chromium.org/b/8853835961923688896",
"utcnow_ts": "1614724873631339"
}
}
[22:41:13.641] Invocation finished in 1h3m31.25547617s with status SUCCEEDED