[05:11:36.853] New invocation is queued and will start shortly
[05:11:38.156] Starting the invocation (attempt 1)
[05:11:38.197] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:11:38.197] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:11:38.197] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021411793694012224",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7a07f651756f3327a1d31d3c56d426ffc2011afa\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021411793694012224",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/7a07f651756f3327a1d31d3c56d426ffc2011afa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a07f651756f3327a1d31d3c56d426ffc2011afa",
"gitiles_ref:refs/heads/master"
]
}
[05:11:39.118] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612674698277549",
"id": "8855981731537457520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7a07f651756f3327a1d31d3c56d426ffc2011afa\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612674698836551",
"tags": [
"build_address:luci.chromium.ci/win-asan/17211",
"builder:win-asan",
"buildset:commit/git/7a07f651756f3327a1d31d3c56d426ffc2011afa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a07f651756f3327a1d31d3c56d426ffc2011afa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021411793694012224",
"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/8855981731537457520/+/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": "1612674698836668",
"url": "https://ci.chromium.org/b/8855981731537457520",
"utcnow_ts": "1612674699107456"
}
}
[05:11:39.118] Task URL: https://ci.chromium.org/b/8855981731537457520
[05:11:39.118] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:2:0) after 1m0s
[05:11:41.587] Received PubSub notification, asking Buildbucket for the build status
[05:11:41.734] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:39.263] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:2:0)
[05:12:39.263] Timer tick, asking Buildbucket for the build status
[05:12:39.442] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:39.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:4:0) after 1m0s
[05:13:39.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:4:0)
[05:13:39.768] Timer tick, asking Buildbucket for the build status
[05:13:39.998] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:13:39.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:5:0) after 1m0s
[05:14:40.114] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:5:0)
[05:14:40.114] Timer tick, asking Buildbucket for the build status
[05:14:40.224] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:40.224] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:6:0) after 1m0s
[05:15:40.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:6:0)
[05:15:40.219] Timer tick, asking Buildbucket for the build status
[05:15:40.657] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:40.657] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:7:0) after 1m0s
[05:16:40.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:7:0)
[05:16:40.750] Timer tick, asking Buildbucket for the build status
[05:16:40.847] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:40.847] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:8:0) after 1m0s
[05:17:40.944] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:8:0)
[05:17:40.944] Timer tick, asking Buildbucket for the build status
[05:17:41.052] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:41.052] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:9:0) after 1m0s
[05:18:41.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:9:0)
[05:18:41.048] Timer tick, asking Buildbucket for the build status
[05:18:41.132] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:41.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:10:0) after 1m0s
[05:19:41.151] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:10:0)
[05:19:41.151] Timer tick, asking Buildbucket for the build status
[05:19:41.223] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:41.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:11:0) after 1m0s
[05:20:41.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:11:0)
[05:20:41.231] Timer tick, asking Buildbucket for the build status
[05:20:41.591] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:41.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:12:0) after 1m0s
[05:21:41.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:12:0)
[05:21:41.683] Timer tick, asking Buildbucket for the build status
[05:21:41.787] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:41.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:13:0) after 1m0s
[05:22:41.905] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:13:0)
[05:22:41.905] Timer tick, asking Buildbucket for the build status
[05:22:42.775] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:42.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:14:0) after 1m0s
[05:23:43.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:14:0)
[05:23:43.662] Timer tick, asking Buildbucket for the build status
[05:23:44.157] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:44.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:15:0) after 1m0s
[05:24:44.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:15:0)
[05:24:44.300] Timer tick, asking Buildbucket for the build status
[05:24:44.569] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:44.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:16:0) after 1m0s
[05:25:44.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:16:0)
[05:25:44.665] Timer tick, asking Buildbucket for the build status
[05:25:44.934] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:44.934] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:17:0) after 1m0s
[05:26:44.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:17:0)
[05:26:44.989] Timer tick, asking Buildbucket for the build status
[05:26:45.141] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:45.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:18:0) after 1m0s
[05:27:45.321] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:18:0)
[05:27:45.321] Timer tick, asking Buildbucket for the build status
[05:27:46.412] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:46.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:19:0) after 1m0s
[05:28:46.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:19:0)
[05:28:46.490] Timer tick, asking Buildbucket for the build status
[05:28:46.644] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:46.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:20:0) after 1m0s
[05:29:46.735] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:20:0)
[05:29:46.735] Timer tick, asking Buildbucket for the build status
[05:29:46.904] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:46.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:21:0) after 1m0s
[05:30:47.027] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:21:0)
[05:30:47.027] Timer tick, asking Buildbucket for the build status
[05:30:47.239] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:47.239] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:22:0) after 1m0s
[05:31:47.421] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:22:0)
[05:31:47.421] Timer tick, asking Buildbucket for the build status
[05:31:47.814] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:47.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:23:0) after 1m0s
[05:32:47.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:23:0)
[05:32:47.830] Timer tick, asking Buildbucket for the build status
[05:32:47.949] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:47.949] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:24:0) after 1m0s
[05:33:47.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:24:0)
[05:33:47.996] Timer tick, asking Buildbucket for the build status
[05:33:48.121] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:48.121] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:25:0) after 1m0s
[05:34:48.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:25:0)
[05:34:48.145] Timer tick, asking Buildbucket for the build status
[05:34:48.279] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:48.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:26:0) after 1m0s
[05:35:48.337] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:26:0)
[05:35:48.337] Timer tick, asking Buildbucket for the build status
[05:35:48.533] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:48.533] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:27:0) after 1m0s
[05:36:48.618] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:27:0)
[05:36:48.618] Timer tick, asking Buildbucket for the build status
[05:36:48.771] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:48.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:28:0) after 1m0s
[05:37:48.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:28:0)
[05:37:48.833] Timer tick, asking Buildbucket for the build status
[05:37:48.962] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:48.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:29:0) after 1m0s
[05:38:49.084] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:29:0)
[05:38:49.084] Timer tick, asking Buildbucket for the build status
[05:38:49.148] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:49.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:30:0) after 1m0s
[05:39:49.166] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:30:0)
[05:39:49.166] Timer tick, asking Buildbucket for the build status
[05:39:49.299] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:49.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:31:0) after 1m0s
[05:40:49.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:31:0)
[05:40:49.295] Timer tick, asking Buildbucket for the build status
[05:40:49.414] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:49.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:32:0) after 1m0s
[05:41:49.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:32:0)
[05:41:49.558] Timer tick, asking Buildbucket for the build status
[05:41:49.662] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:49.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:33:0) after 1m0s
[05:42:49.672] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:33:0)
[05:42:49.672] Timer tick, asking Buildbucket for the build status
[05:42:50.271] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:50.271] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:34:0) after 1m0s
[05:43:50.300] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:34:0)
[05:43:50.300] Timer tick, asking Buildbucket for the build status
[05:43:50.386] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:50.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:35:0) after 1m0s
[05:44:50.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:35:0)
[05:44:50.503] Timer tick, asking Buildbucket for the build status
[05:44:50.814] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:50.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:36:0) after 1m0s
[05:45:51.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:36:0)
[05:45:51.003] Timer tick, asking Buildbucket for the build status
[05:45:51.615] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:51.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:37:0) after 1m0s
[05:46:51.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:37:0)
[05:46:51.719] Timer tick, asking Buildbucket for the build status
[05:46:51.851] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:51.851] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:38:0) after 1m0s
[05:47:51.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:38:0)
[05:47:51.926] Timer tick, asking Buildbucket for the build status
[05:47:52.256] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:52.256] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:39:0) after 1m0s
[05:48:52.390] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:39:0)
[05:48:52.390] Timer tick, asking Buildbucket for the build status
[05:48:52.554] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:52.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:40:0) after 1m0s
[05:49:52.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:40:0)
[05:49:52.708] Timer tick, asking Buildbucket for the build status
[05:49:53.290] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:53.290] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:41:0) after 1m0s
[05:50:53.528] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:41:0)
[05:50:53.528] Timer tick, asking Buildbucket for the build status
[05:50:53.792] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:53.792] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:42:0) after 1m0s
[05:51:53.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:42:0)
[05:51:53.914] Timer tick, asking Buildbucket for the build status
[05:51:54.041] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:54.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:43:0) after 1m0s
[05:52:54.049] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:43:0)
[05:52:54.049] Timer tick, asking Buildbucket for the build status
[05:52:54.133] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:52:54.133] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:44:0) after 1m0s
[05:53:54.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:44:0)
[05:53:54.124] Timer tick, asking Buildbucket for the build status
[05:53:54.232] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:53:54.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:45:0) after 1m0s
[05:54:54.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:45:0)
[05:54:54.314] Timer tick, asking Buildbucket for the build status
[05:54:54.635] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:54:54.635] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:46:0) after 1m0s
[05:55:54.688] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:46:0)
[05:55:54.688] Timer tick, asking Buildbucket for the build status
[05:55:54.918] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:55:54.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:47:0) after 1m0s
[05:56:55.055] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:47:0)
[05:56:55.055] Timer tick, asking Buildbucket for the build status
[05:56:55.156] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:56:55.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:48:0) after 1m0s
[05:57:55.325] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:48:0)
[05:57:55.325] Timer tick, asking Buildbucket for the build status
[05:57:55.393] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:57:55.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:49:0) after 1m0s
[05:58:55.450] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:49:0)
[05:58:55.450] Timer tick, asking Buildbucket for the build status
[05:58:55.651] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:58:55.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:50:0) after 1m0s
[05:59:55.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:50:0)
[05:59:55.706] Timer tick, asking Buildbucket for the build status
[05:59:55.942] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:59:55.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:51:0) after 1m0s
[06:00:55.978] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:51:0)
[06:00:55.978] Timer tick, asking Buildbucket for the build status
[06:00:56.419] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:00:56.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:52:0) after 1m0s
[06:01:56.457] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:52:0)
[06:01:56.457] Timer tick, asking Buildbucket for the build status
[06:01:56.569] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:01:56.569] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:53:0) after 1m0s
[06:02:56.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:53:0)
[06:02:56.589] Timer tick, asking Buildbucket for the build status
[06:02:56.864] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:02:56.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:54:0) after 1m0s
[06:03:56.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:54:0)
[06:03:56.861] Timer tick, asking Buildbucket for the build status
[06:03:57.041] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:03:57.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:55:0) after 1m0s
[06:04:57.059] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:55:0)
[06:04:57.059] Timer tick, asking Buildbucket for the build status
[06:04:57.418] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:04:57.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:56:0) after 1m0s
[06:05:57.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:56:0)
[06:05:57.531] Timer tick, asking Buildbucket for the build status
[06:05:57.645] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:05:57.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:57:0) after 1m0s
[06:06:57.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:57:0)
[06:06:57.708] Timer tick, asking Buildbucket for the build status
[06:06:58.383] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:06:58.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:58:0) after 1m0s
[06:07:58.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:58:0)
[06:07:58.491] Timer tick, asking Buildbucket for the build status
[06:07:58.623] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:07:58.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:59:0) after 1m0s
[06:08:58.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:59:0)
[06:08:58.733] Timer tick, asking Buildbucket for the build status
[06:08:58.839] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:08:58.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:60:0) after 1m0s
[06:09:58.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:60:0)
[06:09:58.903] Timer tick, asking Buildbucket for the build status
[06:09:59.010] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:09:59.010] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:61:0) after 1m0s
[06:10:59.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:61:0)
[06:10:59.031] Timer tick, asking Buildbucket for the build status
[06:10:59.310] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:10:59.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:62:0) after 1m0s
[06:11:59.387] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:62:0)
[06:11:59.387] Timer tick, asking Buildbucket for the build status
[06:11:59.645] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:11:59.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:63:0) after 1m0s
[06:12:59.664] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:63:0)
[06:12:59.664] Timer tick, asking Buildbucket for the build status
[06:13:00.206] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:13:00.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:64:0) after 1m0s
[06:14:00.180] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:64:0)
[06:14:00.180] Timer tick, asking Buildbucket for the build status
[06:14:00.299] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:14:00.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:65:0) after 1m0s
[06:15:00.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:65:0)
[06:15:00.411] Timer tick, asking Buildbucket for the build status
[06:15:00.920] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:15:00.920] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:66:0) after 1m0s
[06:16:00.913] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:66:0)
[06:16:00.913] Timer tick, asking Buildbucket for the build status
[06:16:02.680] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:16:02.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:67:0) after 1m0s
[06:17:02.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:67:0)
[06:17:02.716] Timer tick, asking Buildbucket for the build status
[06:17:02.994] Build 8855981731537457520: status "STARTED", result "", failure_reason "", cancelation_reason ""
[06:17:02.994] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021411793694012224:68:0) after 1m0s
[06:17:46.448] Received PubSub notification, asking Buildbucket for the build status
[06:17:46.516] Build 8855981731537457520: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[06:17:46.516] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612678664087145",
"created_by": "project:chromium",
"created_ts": "1612674698277549",
"id": "8855981731537457520",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7a07f651756f3327a1d31d3c56d426ffc2011afa\"}}",
"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\": \"7a07f651756f3327a1d31d3c56d426ffc2011afa\", \"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\": 17211, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"d820ec7b3e5f9412d1ceb55d31b3f7dc09e3d16d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"7a07f651756f3327a1d31d3c56d426ffc2011afa\", \"got_revision_cp\": \"refs/heads/master@{#851517}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"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\": \"7a07f651756f3327a1d31d3c56d426ffc2011afa\", \"swarm_hashes_refs/heads/master(at){#851517}_without_patch\": {\"absl_hardening_tests\": \"2f8399d60fdc164c12d7640d5833167a68be85d7\", \"accessibility_unittests\": \"3ea2c4b8c13ae19abec987c7311616937946d7a7\", \"angle_unittests\": \"ce3d4b7e826cd59bba78a9bc206d42a22908f7e4\", \"app_shell_unittests\": \"5c713379eebd5de2d529577ea0b54632a2fcea3c\", \"aura_unittests\": \"b5fcaa3af0c3f277f0137ca6dd92a536086018e1\", \"base_unittests\": \"8e82ec02a9d4aabad00e8be3645409eabe7db316\", \"base_util_unittests\": \"e0fbe8139789c061712ada98ff7094b73e10a86d\", \"blink_common_unittests\": \"124f0ff9dcd1c8aecc1e5b9afcfa18a42de485e2\", \"blink_fuzzer_unittests\": \"d6d841ac41d0ed20653a91a738d49d7b178772da\", \"blink_heap_unittests\": \"95dd4fd3ed17704814d615e0b1f6262291610433\", \"blink_platform_unittests\": \"da89d10462245f1129640e8fad399a3887c4bbbc\", \"blink_unittests\": \"610299441f20f75257ccb88e1c2726e8a6753fb2\", \"boringssl_crypto_tests\": \"abe1fdbd3803ac5318a41c3b61587dcc0b91a829\", \"boringssl_ssl_tests\": \"41911704fdab1339822244a682545cedbda02b57\", \"browser_switcher_bho_unittests\": \"08c334ea0f693f1f44fd7a5ba71331f0b05ecf1f\", \"browser_tests\": \"b0fb7c9f855e580cdabc9bfbf2d3dae1842a53df\", \"capture_unittests\": \"96e7424386c1f4e11f13124acda3388601af8410\", \"cast_unittests\": \"e161e9bb1b7b40e2b4c7be0f975d7be85920bd4b\", \"cc_unittests\": \"d879a549468ec8dc0284fee4ca54e017d5616256\", \"chrome_app_unittests\": \"ed9dd414b6152918d4668876b69a37275315a83f\", \"chrome_cleaner_unittests\": \"5872f47f4b0cbcf76d2e7e92e4df9e258dd6a177\", \"chrome_elf_unittests\": \"15e8b38b0f5961dbbde194bebbff196c502b0b51\", \"chromedriver_unittests\": \"4375e5e22f73ba39726061252ca9df8ce2153987\", \"components_browsertests\": \"5fae83444fd5cf98b3e8902e3681305afbf5c03d\", \"components_unittests\": \"aa038407e536bbd899a54c76666c3288583d4593\", \"compositor_unittests\": \"fbf5d6abb80b1663dd46e8378d00eaf48eb8fe58\", \"content_browsertests\": \"c5a0920adc1f098c513774ddfc23d94ab4d66049\", \"content_unittests\": \"1885630dbaaac5bb8a2e5d67428a2fc23fb0abd5\", \"courgette_unittests\": \"5cbf4c11f974c2a14dff1e27e304fbd037541db9\", \"crashpad_tests\": \"1f04e0ef58248a7277e2621011e85ecffe49732e\", \"cronet_tests\": \"19b47ad6f93c3e79a1e3553ab561116cce89144b\", \"cronet_unittests\": \"377696f05f9a0b4b44ea27558f5116a57bfbde3b\", \"crypto_unittests\": \"0cabc2b7759f1a711040a7fbe728e53082f5100f\", \"delayloads_unittests\": \"fb9b7a7d3777af0afd4bfc05ff108936206493c3\", \"device_unittests\": \"217d9addd5e8c92c694b37487c10a24ef9bd31ad\", \"display_unittests\": \"eb1de063c3d590ad1dd99822760d5d7ae19bb332\", \"elevation_service_unittests\": \"3a62527b88956fb1085b899c7b3695eed62d26db\", \"events_unittests\": \"5668813fe3bc910891dabb045a5d28172ae0be08\", \"extensions_browsertests\": \"a7a0c02bf1a2e92ea3d2f08d6ddcbe13bbf05598\", \"extensions_unittests\": \"55a17f3aae6a0998d698b26d94ef18d16e52d641\", \"filesystem_service_unittests\": \"c7e26e2f624bc670a22add61f7744852e1f4400f\", \"gcm_unit_tests\": \"1ad6c513788b6e4303105b8bcef95de54e821272\", \"gcp_unittests\": \"4da7a891ca00ebb4404962e00f49a523f368c5f5\", \"gfx_unittests\": \"fd694b55fe748ace014164fff5002826c4732e3d\", \"gin_unittests\": \"d3222e9e3cbf85fcb1ecee854b80429620d9aa34\", \"google_apis_unittests\": \"9449a8e9ceefe58067974cc665a8b088fcd2183c\", \"gpu_unittests\": \"d322c89c2d866eaeca7ee2b6987293824e899aa3\", \"gwp_asan_unittests\": \"1c6843dbd12f9ea743e42676a716a314bcc3f1a3\", \"headless_browsertests\": \"7cd9313ceded2b607b50749531aa84b5bf96cbcd\", \"headless_unittests\": \"31c18863fa7ee554f20b9cbaf81ab8a18f8fd38b\", \"install_static_unittests\": \"5bcae5a6837044fe4108ff9990693930a7018e90\", \"installer_util_unittests\": \"11b1a15304ef1d39e0a46a69e9294dbf1040ff7c\", \"interactive_ui_tests\": \"dd05c6f8e06b9b417a5d236c92542318f7f6aa6f\", \"ipc_tests\": \"f8e735e0dfefbd6d6a8bb8fc20d171d6203bc8e5\", \"jingle_unittests\": \"7c8825426c64eb4687f3c6ee9523b55d240115b4\", \"latency_unittests\": \"5173c2e3f3d99ddd62720e63d2126319a453ec84\", \"libjingle_xmpp_unittests\": \"3259afdf4f7a2ec5c69eb058cca51ca4068ef8e9\", \"liburlpattern_unittests\": \"9a69247609a880e7d03a21a30d6a9fe66f836dd3\", \"media_blink_unittests\": \"4c1d2d39a2011913759722bcdf59e1e0916fcceb\", \"media_unittests\": \"48b16a5ce844c6e454aee730ffab8fa6ed387259\", \"message_center_unittests\": \"fd548404867d7a31d496821859b2d78b338676d9\", \"midi_unittests\": \"3c2848eeb981d2acf7712f940b7980c7f619c4f1\", \"mojo_core_unittests\": \"3f35e23b9ed881628f2ffce8aedadbc5d93325dd\", \"mojo_unittests\": \"ccc1fb0baa14ce8bb2180d2c698bd282c7ec88db\", \"nacl_loader_unittests\": \"ceccdef43051da5665479bd5946a1274f2b896d3\", \"native_theme_unittests\": \"14aa7e979409ea8e5c6cf150e854ff014f8de487\", \"net_unittests\": \"d33eebfe8a7a35f586a5521498f78196fdcbcc33\", \"notification_helper_unittests\": \"4374a7d0017300504e6fc56bf7331090720815eb\", \"pdf_unittests\": \"9c78bdee6a254845fd0a0de0b96f69c9f204a247\", \"perfetto_unittests\": \"d8947bc4f932eb3d6065393d99973f91ffebde32\", \"ppapi_unittests\": \"da01524feba03862250d2a4b35837fdcd8b687aa\", \"printing_unittests\": \"6a72152efae441cc2bb1b8f946321fcf5550f86a\", \"remoting_unittests\": \"d66d730638912925812648b1bca45c465830b4fd\", \"sbox_integration_tests\": \"c2a579e91fe7b634ee8bdfb5d5cf41651be8f2a2\", \"sbox_unittests\": \"9d6712a91892336b6b88637176803ecdba66c737\", \"sbox_validation_tests\": \"ea03ef90178524e5aef4235567d963a2e54503cd\", \"service_manager_unittests\": \"c86ba1c449a0baeaa626a8499c985e29053230a3\", \"services_unittests\": \"f4c451c1e0af2444cdd7f0c63c7e186bf80be159\", \"setup_unittests\": \"80a67d278abab4f2e4cd74cb2400de1b2ad020c1\", \"shell_dialogs_unittests\": \"32d5e46e4b0bcd1540b276790aa3b411567a005b\", \"skia_unittests\": \"06912d46b79e0e667343a1b92c9d9fbff617a869\", \"snapshot_unittests\": \"797d6a81d7441ff172b45cc332756026136e0c18\", \"sql_unittests\": \"e84560be6ed2f4fbb313723472ef8b7eb8e75e31\", \"storage_unittests\": \"bbec9c39caece94fb6aa18f104aa99eee029194c\", \"sync_integration_tests\": \"3e894f67d5432b944f8945e3b19ecc027b4b0e94\", \"traffic_annotation_auditor_unittests\": \"a82652f22e139d1e9885495be8fe54f5cc1672ea\", \"ui_base_unittests\": \"3fa789ae6c87b8fb9dce1eb7e3309edc2b1f8740\", \"ui_touch_selection_unittests\": \"1d482049db6dd1a6a39c8daf2d24d848d7da19b8\", \"unit_tests\": \"d31ebd25b243c17f44d296dfbcbf5decffac6a2d\", \"updater_tests\": \"016f82968ed3740cde3ee16abbe4d6da4b4b616e\", \"url_unittests\": \"d2a2bfd42bd7ccde155620c5da21204da233c36b\", \"views_unittests\": \"a107795152f06f799ee3d9379cd37a7702a471bc\", \"viz_unittests\": \"b1500cdb06ae242bc0f1f1ff2c4494c55056c6e5\", \"vr_common_unittests\": \"87e1a172e9c2a4dc43f77c5dc2fde6034f2083a4\", \"vr_pixeltests\": \"9aad9f669e18a122c7efb988b24b05c3e4fe794b\", \"weblayer_browsertests\": \"2f5363f2791d0a5ef5bbd7e0fe2ba1210806b0b3\", \"weblayer_unittests\": \"373a80847c3eb5dc81a5fbc87545813f4ba7c898\", \"wm_unittests\": \"1c6e1aefc4530db4ef348a6db4336967f45eda75\", \"wtf_unittests\": \"e7a6f3f0abc9d02a52ba697655ca1e662d85511b\", \"zlib_unittests\": \"d2c329ce0d677743671bda2c0943bcc8d9fcfd1f\", \"zucchini_unittests\": \"7fb8b1dc004c006cb3e82ab353f8492a5d0a09a3\"}}, \"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\": [\"5676-c76598c\"], \"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": "1612674700964729",
"status": "COMPLETED",
"status_changed_ts": "1612678664866266",
"tags": [
"build_address:luci.chromium.ci/win-asan/17211",
"builder:win-asan",
"buildset:commit/git/7a07f651756f3327a1d31d3c56d426ffc2011afa",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7a07f651756f3327a1d31d3c56d426ffc2011afa",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021411793694012224",
"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/8855981731537457520/+/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:5193b89762826810",
"user_agent:luci-scheduler"
],
"updated_ts": "1612678664884646",
"url": "https://ci.chromium.org/b/8855981731537457520",
"utcnow_ts": "1612678666507798"
}
}
[06:17:46.516] Invocation finished in 1h6m9.678938191s with status SUCCEEDED