[15:44:39.131] New invocation is queued and will start shortly
[15:44:40.348] Starting the invocation (attempt 1)
[15:44:40.393] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:44:40.393] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:44:40.393] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9029888081231908304",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9029888081231908304",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7",
"gitiles_ref:refs/heads/master"
]
}
[15:44:41.643] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1604591080540123",
"id": "8864458019089571696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604591081436069",
"tags": [
"build_address:luci.chromium.ci/win-asan/15480",
"builder:win-asan",
"buildset:commit/git/dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029888081231908304",
"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/8864458019089571696/+/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": "1604591081436495",
"url": "https://ci.chromium.org/b/8864458019089571696",
"utcnow_ts": "1604591081634357"
}
}
[15:44:41.644] Task URL: https://ci.chromium.org/b/8864458019089571696
[15:44:41.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:2:0) after 1m0s
[15:44:48.741] Received PubSub notification, asking Buildbucket for the build status
[15:44:48.904] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:41.832] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:2:0)
[15:45:41.832] Timer tick, asking Buildbucket for the build status
[15:45:41.906] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:45:41.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:4:0) after 1m0s
[15:46:41.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:4:0)
[15:46:41.944] Timer tick, asking Buildbucket for the build status
[15:46:42.045] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:46:42.045] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:5:0) after 1m0s
[15:47:42.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:5:0)
[15:47:42.166] Timer tick, asking Buildbucket for the build status
[15:47:42.340] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:47:42.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:6:0) after 1m0s
[15:48:42.442] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:6:0)
[15:48:42.442] Timer tick, asking Buildbucket for the build status
[15:48:42.893] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:48:42.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:7:0) after 1m0s
[15:49:42.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:7:0)
[15:49:42.916] Timer tick, asking Buildbucket for the build status
[15:49:43.058] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:49:43.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:8:0) after 1m0s
[15:50:43.178] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:8:0)
[15:50:43.178] Timer tick, asking Buildbucket for the build status
[15:50:43.422] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:43.422] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:9:0) after 1m0s
[15:51:43.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:9:0)
[15:51:43.487] Timer tick, asking Buildbucket for the build status
[15:51:43.571] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:43.571] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:10:0) after 1m0s
[15:52:43.632] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:10:0)
[15:52:43.632] Timer tick, asking Buildbucket for the build status
[15:52:43.703] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:43.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:11:0) after 1m0s
[15:53:43.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:11:0)
[15:53:43.698] Timer tick, asking Buildbucket for the build status
[15:53:43.903] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:43.903] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:12:0) after 1m0s
[15:54:43.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:12:0)
[15:54:43.949] Timer tick, asking Buildbucket for the build status
[15:54:44.019] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:44.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:13:0) after 1m0s
[15:55:44.075] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:13:0)
[15:55:44.075] Timer tick, asking Buildbucket for the build status
[15:55:44.203] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:44.203] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:14:0) after 1m0s
[15:56:44.234] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:14:0)
[15:56:44.234] Timer tick, asking Buildbucket for the build status
[15:56:44.315] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:44.315] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:15:0) after 1m0s
[15:57:44.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:15:0)
[15:57:44.350] Timer tick, asking Buildbucket for the build status
[15:57:44.517] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:44.517] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:16:0) after 1m0s
[15:58:44.506] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:16:0)
[15:58:44.506] Timer tick, asking Buildbucket for the build status
[15:58:44.572] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:44.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:17:0) after 1m0s
[15:59:44.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:17:0)
[15:59:44.662] Timer tick, asking Buildbucket for the build status
[15:59:44.971] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:44.971] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:18:0) after 1m0s
[16:00:45.000] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:18:0)
[16:00:45.000] Timer tick, asking Buildbucket for the build status
[16:00:45.151] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:45.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:19:0) after 1m0s
[16:01:45.291] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:19:0)
[16:01:45.291] Timer tick, asking Buildbucket for the build status
[16:01:45.414] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:45.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:20:0) after 1m0s
[16:02:45.508] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:20:0)
[16:02:45.508] Timer tick, asking Buildbucket for the build status
[16:02:45.741] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:45.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:21:0) after 1m0s
[16:03:45.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:21:0)
[16:03:45.765] Timer tick, asking Buildbucket for the build status
[16:03:45.894] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:45.894] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:22:0) after 1m0s
[16:04:45.918] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:22:0)
[16:04:45.918] Timer tick, asking Buildbucket for the build status
[16:04:46.046] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:46.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:23:0) after 1m0s
[16:05:46.143] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:23:0)
[16:05:46.143] Timer tick, asking Buildbucket for the build status
[16:05:46.208] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:46.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:24:0) after 1m0s
[16:06:46.262] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:24:0)
[16:06:46.262] Timer tick, asking Buildbucket for the build status
[16:06:46.377] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:46.377] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:25:0) after 1m0s
[16:07:46.459] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:25:0)
[16:07:46.459] Timer tick, asking Buildbucket for the build status
[16:07:46.908] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:46.908] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:26:0) after 1m0s
[16:08:46.997] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:26:0)
[16:08:46.997] Timer tick, asking Buildbucket for the build status
[16:08:47.245] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:47.245] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:27:0) after 1m0s
[16:09:47.287] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:27:0)
[16:09:47.287] Timer tick, asking Buildbucket for the build status
[16:09:47.486] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:47.486] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:28:0) after 1m0s
[16:10:47.593] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:28:0)
[16:10:47.593] Timer tick, asking Buildbucket for the build status
[16:10:47.725] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:47.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:29:0) after 1m0s
[16:11:47.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:29:0)
[16:11:47.768] Timer tick, asking Buildbucket for the build status
[16:11:47.866] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:47.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:30:0) after 1m0s
[16:12:47.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:30:0)
[16:12:47.923] Timer tick, asking Buildbucket for the build status
[16:12:48.065] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:48.065] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:31:0) after 1m0s
[16:13:48.155] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:31:0)
[16:13:48.155] Timer tick, asking Buildbucket for the build status
[16:13:48.323] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:48.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:32:0) after 1m0s
[16:14:48.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:32:0)
[16:14:48.448] Timer tick, asking Buildbucket for the build status
[16:14:48.882] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:48.882] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:33:0) after 1m0s
[16:15:48.933] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:33:0)
[16:15:48.933] Timer tick, asking Buildbucket for the build status
[16:15:49.159] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:49.159] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:34:0) after 1m0s
[16:16:49.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:34:0)
[16:16:49.245] Timer tick, asking Buildbucket for the build status
[16:16:49.859] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:49.859] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:35:0) after 1m0s
[16:17:49.864] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:35:0)
[16:17:49.864] Timer tick, asking Buildbucket for the build status
[16:17:50.400] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:50.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:36:0) after 1m0s
[16:18:50.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:36:0)
[16:18:50.559] Timer tick, asking Buildbucket for the build status
[16:18:51.016] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:51.016] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:37:0) after 1m0s
[16:19:51.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:37:0)
[16:19:51.124] Timer tick, asking Buildbucket for the build status
[16:19:51.466] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:51.466] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:38:0) after 1m0s
[16:20:51.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:38:0)
[16:20:51.505] Timer tick, asking Buildbucket for the build status
[16:20:52.013] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:52.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:39:0) after 1m0s
[16:21:52.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:39:0)
[16:21:52.133] Timer tick, asking Buildbucket for the build status
[16:21:52.741] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:52.741] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:40:0) after 1m0s
[16:22:52.770] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:40:0)
[16:22:52.770] Timer tick, asking Buildbucket for the build status
[16:22:52.932] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:52.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:41:0) after 1m0s
[16:23:52.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:41:0)
[16:23:52.998] Timer tick, asking Buildbucket for the build status
[16:23:53.134] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:53.134] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:42:0) after 1m0s
[16:24:53.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:42:0)
[16:24:53.198] Timer tick, asking Buildbucket for the build status
[16:24:53.630] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:53.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:43:0) after 1m0s
[16:25:53.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:43:0)
[16:25:53.662] Timer tick, asking Buildbucket for the build status
[16:25:53.981] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:53.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:44:0) after 1m0s
[16:26:54.013] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:44:0)
[16:26:54.013] Timer tick, asking Buildbucket for the build status
[16:26:54.639] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:54.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:45:0) after 1m0s
[16:27:54.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:45:0)
[16:27:54.703] Timer tick, asking Buildbucket for the build status
[16:27:54.837] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:54.837] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:46:0) after 1m0s
[16:28:54.993] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:46:0)
[16:28:54.993] Timer tick, asking Buildbucket for the build status
[16:28:55.064] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:55.064] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:47:0) after 1m0s
[16:29:55.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:47:0)
[16:29:55.156] Timer tick, asking Buildbucket for the build status
[16:29:55.258] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:55.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:48:0) after 1m0s
[16:30:55.368] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:48:0)
[16:30:55.368] Timer tick, asking Buildbucket for the build status
[16:30:55.451] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:55.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:49:0) after 1m0s
[16:31:55.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:49:0)
[16:31:55.560] Timer tick, asking Buildbucket for the build status
[16:31:55.631] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:55.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:50:0) after 1m0s
[16:32:55.788] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:50:0)
[16:32:55.788] Timer tick, asking Buildbucket for the build status
[16:32:55.972] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:55.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:51:0) after 1m0s
[16:33:56.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:51:0)
[16:33:56.087] Timer tick, asking Buildbucket for the build status
[16:33:56.221] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:56.221] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:52:0) after 1m0s
[16:34:56.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:52:0)
[16:34:56.239] Timer tick, asking Buildbucket for the build status
[16:34:56.352] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:56.352] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:53:0) after 1m0s
[16:35:56.374] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:53:0)
[16:35:56.374] Timer tick, asking Buildbucket for the build status
[16:35:56.506] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:56.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:54:0) after 1m0s
[16:36:56.594] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:54:0)
[16:36:56.594] Timer tick, asking Buildbucket for the build status
[16:36:56.720] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:56.720] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:55:0) after 1m0s
[16:37:56.995] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:55:0)
[16:37:56.995] Timer tick, asking Buildbucket for the build status
[16:37:57.079] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:57.079] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:56:0) after 1m0s
[16:38:57.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:56:0)
[16:38:57.204] Timer tick, asking Buildbucket for the build status
[16:38:57.318] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:57.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:57:0) after 1m0s
[16:39:57.359] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:57:0)
[16:39:57.359] Timer tick, asking Buildbucket for the build status
[16:39:57.477] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:57.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:58:0) after 1m0s
[16:40:57.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:58:0)
[16:40:57.584] Timer tick, asking Buildbucket for the build status
[16:40:58.004] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:58.004] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:59:0) after 1m0s
[16:41:58.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:59:0)
[16:41:58.397] Timer tick, asking Buildbucket for the build status
[16:41:58.572] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:58.572] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:60:0) after 1m0s
[16:42:58.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:60:0)
[16:42:58.717] Timer tick, asking Buildbucket for the build status
[16:42:58.863] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:58.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:61:0) after 1m0s
[16:43:59.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:61:0)
[16:43:59.055] Timer tick, asking Buildbucket for the build status
[16:43:59.222] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:59.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:62:0) after 1m0s
[16:44:59.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:62:0)
[16:44:59.430] Timer tick, asking Buildbucket for the build status
[16:44:59.995] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:59.995] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:63:0) after 1m0s
[16:46:00.118] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:63:0)
[16:46:00.118] Timer tick, asking Buildbucket for the build status
[16:46:00.415] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:00.415] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:64:0) after 1m0s
[16:47:00.518] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:64:0)
[16:47:00.518] Timer tick, asking Buildbucket for the build status
[16:47:00.618] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:00.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:65:0) after 1m0s
[16:48:00.749] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:65:0)
[16:48:00.749] Timer tick, asking Buildbucket for the build status
[16:48:01.003] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:01.003] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:66:0) after 1m0s
[16:49:01.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:66:0)
[16:49:01.422] Timer tick, asking Buildbucket for the build status
[16:49:01.586] Build 8864458019089571696: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:01.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9029888081231908304:67:0) after 1m0s
[16:49:43.156] Received PubSub notification, asking Buildbucket for the build status
[16:49:43.292] Build 8864458019089571696: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:49:43.292] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1604594981398108",
"created_by": "project:chromium",
"created_ts": "1604591080540123",
"id": "8864458019089571696",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7\"}}",
"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\": \"dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15480, \"clang_revision\": \"llvmorg-12-init-10666-gc9f69ee7-1\", \"got_angle_revision\": \"3942e801a65b780296f526f10704c9668d499942\", \"got_dawn_revision\": \"e87a8c466fb1652aedc2bfea464ae0b99782a357\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7\", \"got_revision_cp\": \"refs/heads/master@{#824426}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"ad54c71632cc0f18584a7009f1e6fc44f5453bc7\", \"got_v8_revision\": \"937e42d5f246b3d04092869127d2888668de7acd\", \"got_v8_revision_cp\": \"refs/heads/8.8.239@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7\", \"swarm_hashes_refs/heads/master(at){#824426}_without_patch\": {\"absl_hardening_tests\": \"82904aa0b30adcc2075512e6bee412bbbb22829c\", \"accessibility_unittests\": \"645aec1d7d807905060ff655f2eb73436bd5ea01\", \"angle_unittests\": \"dff6e0862e73b6a2fc975d2f7549ee1cbea01492\", \"app_shell_unittests\": \"64e1ec5a316ce8b5e5806d69fad6a7822fc55cd6\", \"aura_unittests\": \"86e71c704e3afa809fb3dec4135d4fca76a8f6b8\", \"base_unittests\": \"fe98cdd8725545dae8e619d9786c218c97566c6a\", \"base_util_unittests\": \"b153701ba40c088eec43c5e1170294b5db4f47ad\", \"blink_common_unittests\": \"d690f26d5cf1b97c35128d98103d249dd7af2cf0\", \"blink_fuzzer_unittests\": \"e797a76125f46be18881a8b7c96c621f556dea68\", \"blink_heap_unittests\": \"3ec28ffe700311781f95cc90eaf703c6cdaf07d5\", \"blink_platform_unittests\": \"0719286ffb3a6afe784532004a9eb4ba83fd5e66\", \"blink_unittests\": \"4f7c0facbd3b7b643f0c478dd86bbf765a415498\", \"boringssl_crypto_tests\": \"ba2ae9bba771b5163c933a0bf65e052ee25bd141\", \"boringssl_ssl_tests\": \"848e8c10319cd8e5be8c0841129dd4a66b23ef8c\", \"browser_switcher_bho_unittests\": \"1f46c3440c42110f9d293f6b8a38db34cc83635e\", \"browser_tests\": \"8023d17bf9bc2981a29db2d8948b5ca618fb6edb\", \"capture_unittests\": \"e150767c5781da729e49e04294d76a3b17cf64a8\", \"cast_unittests\": \"7da1a8cb6f683ec411decf25eb7ad20c49831f91\", \"cc_unittests\": \"b12e8154bafd873dd741ec2eeeef7ccc0b208865\", \"chrome_app_unittests\": \"2e59f462d2c9069cc4e1fe6bf9e5746cb9b70d70\", \"chrome_cleaner_unittests\": \"2f7e48752890de11e386c544c23bc0951326e922\", \"chrome_elf_unittests\": \"99b30def0285896384194a98ecd0381793461e07\", \"chromedriver_unittests\": \"830e97ca870b4dbd60969bbf31f0249f3f52fb22\", \"components_browsertests\": \"b311f1dd59e70d25311d8b42649d59ef18876507\", \"components_unittests\": \"3ecf48a08d4f081c8d766f0363e8cefde4b16394\", \"compositor_unittests\": \"e40371d8fa6dd713e40ed13456b142c59e33e68b\", \"content_browsertests\": \"6b6be8ac6dd0b2023ae0e16ef54c404edc087d3c\", \"content_unittests\": \"bf1e043a3e2b8f6be7aab7c8498119cf37a34af2\", \"courgette_unittests\": \"673bca7a6894485f4d07a68c5b4220d86654c277\", \"crashpad_tests\": \"f61ee89bd14e48315f859c9739b0a6a11735dbfd\", \"cronet_tests\": \"7029fa1ac23d2f779766a53d33e7c1033afbcd01\", \"cronet_unittests\": \"868f3398a9972a6475c87536c97030f69672d9d7\", \"crypto_unittests\": \"acce79b0f3df0b49b157b211886119aa16726e52\", \"delayloads_unittests\": \"2a8f097a433280c4d955845c28cdebedac4f118f\", \"device_unittests\": \"b5304f361df4fbb193337c5dee262d40b1abd85d\", \"display_unittests\": \"2f299810147b73107b0aeffa353b78a39e2c8378\", \"elevation_service_unittests\": \"a5e865ed484ecafb63aca2d0b0c9a77d1bb2be98\", \"events_unittests\": \"13a206691ce72a5ead2924f0ed46ea812087a92d\", \"extensions_browsertests\": \"91d71157639eafe3cda904da9ba4621981694798\", \"extensions_unittests\": \"5d95fb71053c82e929e7bd21a77840bb060c4e6f\", \"filesystem_service_unittests\": \"68cbd6adc7b407f895c7402bf2d5e3acee522054\", \"gcm_unit_tests\": \"26f2a00a34c58a9471cb20518c9f501336fc0abc\", \"gcp_unittests\": \"10106819d6383d499a7adad2a3471022bc429583\", \"gfx_unittests\": \"f8a1cad6cf78fe835393aace7da65d3c269d17ae\", \"gin_unittests\": \"1d0e44c18ee9a19ea299e58517847d18b93cfc69\", \"google_apis_unittests\": \"b75887cad17d6fdc2ddc2009d346443052f29234\", \"gpu_unittests\": \"301952584fd999edb87f1478bc00dd71f9ef13b2\", \"gwp_asan_unittests\": \"e79990af7a4e285a18dca3210259b2d394548faf\", \"headless_browsertests\": \"59e6f6b1be827051f7a2d5bbf820cc0a78951f6e\", \"headless_unittests\": \"2bbad20361844437d9190860e770c2711501857b\", \"install_static_unittests\": \"e23ea3ad586ffe6400f70ba5f4c44e7e294c7511\", \"installer_util_unittests\": \"f79afd6163f64ece59b5f50036170f98f7e2c755\", \"interactive_ui_tests\": \"3ee231db2b599933297bf4e85f195425b35e00cb\", \"ipc_tests\": \"17338e78cd66de4908685cfb2b29f87774995bbf\", \"jingle_unittests\": \"9346c7c79eb732273a09dc0cea0c3848a6f422f6\", \"latency_unittests\": \"a39d2650e76e10091b8a80ea292a1fe601ea8243\", \"libjingle_xmpp_unittests\": \"b88f20401b2d471854e20f41b8fbd9721c960546\", \"liburlpattern_unittests\": \"48ea8a0cb041f7d91299eeb0c6c41581d3394d3f\", \"media_blink_unittests\": \"c8eceda8312995ab172f45e47697fb9c88084de5\", \"media_unittests\": \"b639ec1436c59c1b220ea0f4703565b94c907151\", \"message_center_unittests\": \"fa2e01ce8f55f4255869fb3e40065a50c8107f61\", \"midi_unittests\": \"0cab752305c531c9ebe63369f7a0c3f2e9d6368e\", \"mojo_core_unittests\": \"0ab7cfccc4e758530884a98f7286001a9fbdc4b7\", \"mojo_unittests\": \"e8f5c12e9f5a815c90f0d10b25cab93ee8ed0994\", \"nacl_loader_unittests\": \"0362a0385667e23ee78dcc6aec8256d2cc9ecf85\", \"native_theme_unittests\": \"beb5220194b14d413052cc7436351853133e59bd\", \"net_unittests\": \"6d518a362e22f3d31299dd96069db226de077ae9\", \"notification_helper_unittests\": \"f05630d3285783374391c1d2bc5ed819fa68061d\", \"pdf_unittests\": \"62caced1928099e898692c4e8a72daff579c4fc7\", \"perfetto_unittests\": \"545495c377bd8c4e6a9cc7fb9189565e63b45e6f\", \"ppapi_unittests\": \"e53138e47218ed8db880393951c615fa37428c4d\", \"printing_unittests\": \"01ebf989ee652e7039570625a299cbfd00bd1acf\", \"remoting_unittests\": \"323a376a4a34a570ce0e0e13f62cc30f8e546f0e\", \"sbox_integration_tests\": \"46558932d35dde246790984e80fc68f898d4a28d\", \"sbox_unittests\": \"62b64dcc1c49afa4ab5b996aa71126145b343f05\", \"sbox_validation_tests\": \"ba53524c86ea42b2df4408b64533cb9ef05195cf\", \"service_manager_unittests\": \"c411641d7b8bdb5653e4d8b154b2d2d73b721f46\", \"services_unittests\": \"813e34063a9f13bc13ce7b2ccc599881d0dce714\", \"setup_unittests\": \"f51f560e1de883236ad90b49fa4edf590d54cd9f\", \"shell_dialogs_unittests\": \"7f1b595dedbc69910782a5e272e87294b5f1074e\", \"skia_unittests\": \"6fc54437a15bf67758acac4492a3ab910f11e1f1\", \"snapshot_unittests\": \"6736535e5b453917067f762a4826a01e508c0c19\", \"sql_unittests\": \"29cc497e27be34ade13f742fef3a968e0344cbcd\", \"storage_unittests\": \"c01e4fda708266cf648e0b39e27288de4e069e7b\", \"sync_integration_tests\": \"ff49b55d9c855570b05908244cbe5dfd511b9778\", \"traffic_annotation_auditor_unittests\": \"48904d9888733e8b822f0f3871d7dd44a5c4fb9f\", \"ui_base_unittests\": \"347ed67cec04559e6c76b4dafab0a81425df2e4d\", \"ui_touch_selection_unittests\": \"b7386e2fd49408eeff4d1bd8c1f3d559a89883f9\", \"unit_tests\": \"36af299bcdcbd2b88d24bd16109dad75716df86b\", \"updater_tests\": \"24682e586e771700807a6d38138cd86254955d48\", \"url_unittests\": \"c5a06def78f46b82724877ba6a3f36d1133e7dc0\", \"views_unittests\": \"f5f4165b9c5079bca06da95ecb0e5bfa1ed2abe2\", \"viz_unittests\": \"c9a672210882ee018afa8fb379a592b1b11adbfb\", \"vr_common_unittests\": \"a759be2bad7ef69c02c973d79ab716a10b6caa5f\", \"vr_pixeltests\": \"c34047014d34a97a3b87950a8a4908423dd72586\", \"weblayer_browsertests\": \"56114737568b603bb669e41615dfca1c7d6d6a62\", \"weblayer_unittests\": \"b8feec345b0b4f031b947aed966c2bc08db76452\", \"wm_unittests\": \"81666be63cdd93387e55a78d62e1c19d363bfcaa\", \"wtf_unittests\": \"d3fac49bca4e1af8916e25593954c7ff86c7539d\", \"zlib_unittests\": \"b9ba4e576ee6b585668d374b0c6bf26f7b9a08ab\", \"zucchini_unittests\": \"5a31a8fad1381bf5532faedb9e5ae6c4a03b0a64\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-5-vrjs\"], \"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\": [\"5614-d862eaa\"], \"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": "1604591087922890",
"status": "COMPLETED",
"status_changed_ts": "1604594981710997",
"tags": [
"build_address:luci.chromium.ci/win-asan/15480",
"builder:win-asan",
"buildset:commit/git/dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/dd085f5b08b0e39cbb51ac311ff37f1f40bfbec7",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9029888081231908304",
"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/8864458019089571696/+/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:4fb1e66965b5c810",
"user_agent:luci-scheduler"
],
"updated_ts": "1604594981765419",
"url": "https://ci.chromium.org/b/8864458019089571696",
"utcnow_ts": "1604594983282922"
}
}
[16:49:43.292] Invocation finished in 1h5m4.177958372s with status SUCCEEDED