[04:10:10.936] New invocation is queued and will start shortly
[04:10:12.090] Starting the invocation (attempt 1)
[04:10:12.124] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:10:12.124] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:10:12.124] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020328495059967280",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"559cd59355404a5ecb0fc8d404ee15abada6c958\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020328495059967280",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/559cd59355404a5ecb0fc8d404ee15abada6c958",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/559cd59355404a5ecb0fc8d404ee15abada6c958",
"gitiles_ref:refs/heads/master"
]
}
[04:10:13.330] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613707812297030",
"id": "8854898432971457968",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"559cd59355404a5ecb0fc8d404ee15abada6c958\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613707812987301",
"tags": [
"build_address:luci.chromium.ci/win-asan/17420",
"builder:win-asan",
"buildset:commit/git/559cd59355404a5ecb0fc8d404ee15abada6c958",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/559cd59355404a5ecb0fc8d404ee15abada6c958",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020328495059967280",
"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/8854898432971457968/+/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": "1613707812987485",
"url": "https://ci.chromium.org/b/8854898432971457968",
"utcnow_ts": "1613707813322915"
}
}
[04:10:13.330] Task URL: https://ci.chromium.org/b/8854898432971457968
[04:10:13.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:2:0) after 1m0s
[04:10:16.172] Received PubSub notification, asking Buildbucket for the build status
[04:10:16.243] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:13.362] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:2:0)
[04:11:13.362] Timer tick, asking Buildbucket for the build status
[04:11:13.606] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:13.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:4:0) after 1m0s
[04:12:13.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:4:0)
[04:12:13.648] Timer tick, asking Buildbucket for the build status
[04:12:13.749] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:13.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:5:0) after 1m0s
[04:13:13.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:5:0)
[04:13:13.818] Timer tick, asking Buildbucket for the build status
[04:13:13.945] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:13.945] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:6:0) after 1m0s
[04:14:14.076] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:6:0)
[04:14:14.076] Timer tick, asking Buildbucket for the build status
[04:14:14.163] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:14.163] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:7:0) after 1m0s
[04:15:14.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:7:0)
[04:15:14.276] Timer tick, asking Buildbucket for the build status
[04:15:14.419] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:14.419] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:8:0) after 1m0s
[04:16:14.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:8:0)
[04:16:14.436] Timer tick, asking Buildbucket for the build status
[04:16:14.629] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:14.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:9:0) after 1m0s
[04:17:14.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:9:0)
[04:17:14.700] Timer tick, asking Buildbucket for the build status
[04:17:14.789] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:14.789] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:10:0) after 1m0s
[04:18:14.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:10:0)
[04:18:14.882] Timer tick, asking Buildbucket for the build status
[04:18:15.046] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:15.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:11:0) after 1m0s
[04:19:15.198] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:11:0)
[04:19:15.198] Timer tick, asking Buildbucket for the build status
[04:19:15.302] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:15.302] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:12:0) after 1m0s
[04:20:15.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:12:0)
[04:20:15.393] Timer tick, asking Buildbucket for the build status
[04:20:15.605] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:15.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:13:0) after 1m0s
[04:21:15.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:13:0)
[04:21:15.670] Timer tick, asking Buildbucket for the build status
[04:21:15.891] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:15.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:14:0) after 1m0s
[04:22:15.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:14:0)
[04:22:15.936] Timer tick, asking Buildbucket for the build status
[04:22:16.185] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:16.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:15:0) after 1m0s
[04:23:16.239] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:15:0)
[04:23:16.239] Timer tick, asking Buildbucket for the build status
[04:23:16.341] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:16.341] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:16:0) after 1m0s
[04:24:16.433] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:16:0)
[04:24:16.433] Timer tick, asking Buildbucket for the build status
[04:24:16.588] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:24:16.588] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:17:0) after 1m0s
[04:25:16.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:17:0)
[04:25:16.627] Timer tick, asking Buildbucket for the build status
[04:25:16.993] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:25:16.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:18:0) after 1m0s
[04:26:17.019] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:18:0)
[04:26:17.019] Timer tick, asking Buildbucket for the build status
[04:26:17.096] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:26:17.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:19:0) after 1m0s
[04:27:17.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:19:0)
[04:27:17.094] Timer tick, asking Buildbucket for the build status
[04:27:17.382] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:27:17.382] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:20:0) after 1m0s
[04:28:17.403] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:20:0)
[04:28:17.403] Timer tick, asking Buildbucket for the build status
[04:28:17.522] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:28:17.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:21:0) after 1m0s
[04:29:17.589] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:21:0)
[04:29:17.589] Timer tick, asking Buildbucket for the build status
[04:29:17.668] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:29:17.668] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:22:0) after 1m0s
[04:30:17.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:22:0)
[04:30:17.698] Timer tick, asking Buildbucket for the build status
[04:30:17.942] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:30:17.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:23:0) after 1m0s
[04:31:18.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:23:0)
[04:31:18.042] Timer tick, asking Buildbucket for the build status
[04:31:18.194] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:31:18.194] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:24:0) after 1m0s
[04:32:18.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:24:0)
[04:32:18.232] Timer tick, asking Buildbucket for the build status
[04:32:18.509] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:32:18.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:25:0) after 1m0s
[04:33:18.509] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:25:0)
[04:33:18.509] Timer tick, asking Buildbucket for the build status
[04:33:18.624] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:33:18.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:26:0) after 1m0s
[04:34:18.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:26:0)
[04:34:18.621] Timer tick, asking Buildbucket for the build status
[04:34:19.105] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:34:19.106] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:27:0) after 1m0s
[04:35:19.139] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:27:0)
[04:35:19.139] Timer tick, asking Buildbucket for the build status
[04:35:19.232] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:35:19.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:28:0) after 1m0s
[04:36:19.296] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:28:0)
[04:36:19.296] Timer tick, asking Buildbucket for the build status
[04:36:19.401] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:36:19.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:29:0) after 1m0s
[04:37:19.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:29:0)
[04:37:19.439] Timer tick, asking Buildbucket for the build status
[04:37:19.803] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:37:19.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:30:0) after 1m0s
[04:38:19.889] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:30:0)
[04:38:19.889] Timer tick, asking Buildbucket for the build status
[04:38:19.973] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:19.973] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:31:0) after 1m0s
[04:39:20.041] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:31:0)
[04:39:20.041] Timer tick, asking Buildbucket for the build status
[04:39:20.780] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:20.780] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:32:0) after 1m0s
[04:40:20.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:32:0)
[04:40:20.808] Timer tick, asking Buildbucket for the build status
[04:40:20.918] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:20.918] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:33:0) after 1m0s
[04:41:21.007] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:33:0)
[04:41:21.007] Timer tick, asking Buildbucket for the build status
[04:41:21.270] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:21.270] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:34:0) after 1m0s
[04:42:21.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:34:0)
[04:42:21.367] Timer tick, asking Buildbucket for the build status
[04:42:21.454] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:42:21.454] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:35:0) after 1m0s
[04:43:21.503] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:35:0)
[04:43:21.503] Timer tick, asking Buildbucket for the build status
[04:43:21.912] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:43:21.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:36:0) after 1m0s
[04:44:21.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:36:0)
[04:44:21.989] Timer tick, asking Buildbucket for the build status
[04:44:22.083] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:44:22.083] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:37:0) after 1m0s
[04:45:22.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:37:0)
[04:45:22.128] Timer tick, asking Buildbucket for the build status
[04:45:22.237] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:45:22.237] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:38:0) after 1m0s
[04:46:22.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:38:0)
[04:46:22.406] Timer tick, asking Buildbucket for the build status
[04:46:22.515] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:46:22.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:39:0) after 1m0s
[04:47:22.523] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:39:0)
[04:47:22.523] Timer tick, asking Buildbucket for the build status
[04:47:22.653] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:47:22.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:40:0) after 1m0s
[04:48:22.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:40:0)
[04:48:22.647] Timer tick, asking Buildbucket for the build status
[04:48:22.732] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:48:22.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:41:0) after 1m0s
[04:49:22.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:41:0)
[04:49:22.784] Timer tick, asking Buildbucket for the build status
[04:49:22.862] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:49:22.862] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:42:0) after 1m0s
[04:50:22.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:42:0)
[04:50:22.909] Timer tick, asking Buildbucket for the build status
[04:50:23.037] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:50:23.037] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:43:0) after 1m0s
[04:51:23.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:43:0)
[04:51:23.156] Timer tick, asking Buildbucket for the build status
[04:51:23.241] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:51:23.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:44:0) after 1m0s
[04:52:23.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:44:0)
[04:52:23.373] Timer tick, asking Buildbucket for the build status
[04:52:23.456] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:52:23.456] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:45:0) after 1m0s
[04:53:23.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:45:0)
[04:53:23.599] Timer tick, asking Buildbucket for the build status
[04:53:23.687] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:53:23.687] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:46:0) after 1m0s
[04:54:23.776] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:46:0)
[04:54:23.776] Timer tick, asking Buildbucket for the build status
[04:54:23.919] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:54:23.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:47:0) after 1m0s
[04:55:23.983] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:47:0)
[04:55:23.983] Timer tick, asking Buildbucket for the build status
[04:55:24.095] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:55:24.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:48:0) after 1m0s
[04:56:24.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:48:0)
[04:56:24.148] Timer tick, asking Buildbucket for the build status
[04:56:24.383] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:56:24.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:49:0) after 1m0s
[04:57:24.361] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:49:0)
[04:57:24.361] Timer tick, asking Buildbucket for the build status
[04:57:24.529] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:24.529] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:50:0) after 1m0s
[04:58:24.534] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:50:0)
[04:58:24.534] Timer tick, asking Buildbucket for the build status
[04:58:24.619] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:24.619] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:51:0) after 1m0s
[04:59:24.642] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:51:0)
[04:59:24.642] Timer tick, asking Buildbucket for the build status
[04:59:24.749] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:24.749] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:52:0) after 1m0s
[05:00:25.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:52:0)
[05:00:25.047] Timer tick, asking Buildbucket for the build status
[05:00:25.279] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:25.279] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:53:0) after 1m0s
[05:01:25.436] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:53:0)
[05:01:25.436] Timer tick, asking Buildbucket for the build status
[05:01:26.400] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:26.400] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:54:0) after 1m0s
[05:02:26.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:54:0)
[05:02:26.429] Timer tick, asking Buildbucket for the build status
[05:02:26.726] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:26.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:55:0) after 1m0s
[05:03:26.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:55:0)
[05:03:26.748] Timer tick, asking Buildbucket for the build status
[05:03:27.394] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:27.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:56:0) after 1m0s
[05:04:27.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:56:0)
[05:04:27.407] Timer tick, asking Buildbucket for the build status
[05:04:27.510] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:27.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:57:0) after 1m0s
[05:05:27.622] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:57:0)
[05:05:27.622] Timer tick, asking Buildbucket for the build status
[05:05:27.774] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:27.774] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:58:0) after 1m0s
[05:06:27.814] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:58:0)
[05:06:27.814] Timer tick, asking Buildbucket for the build status
[05:06:27.957] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:27.957] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:59:0) after 1m0s
[05:07:28.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:59:0)
[05:07:28.037] Timer tick, asking Buildbucket for the build status
[05:07:28.153] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:28.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:60:0) after 1m0s
[05:08:28.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:60:0)
[05:08:28.243] Timer tick, asking Buildbucket for the build status
[05:08:28.620] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:28.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:61:0) after 1m0s
[05:09:28.640] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:61:0)
[05:09:28.640] Timer tick, asking Buildbucket for the build status
[05:09:29.433] Build 8854898432971457968: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:29.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020328495059967280:62:0) after 1m0s
[05:10:05.286] Received PubSub notification, asking Buildbucket for the build status
[05:10:05.376] Build 8854898432971457968: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:10:05.376] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613711401291405",
"created_by": "project:chromium",
"created_ts": "1613707812297030",
"id": "8854898432971457968",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"559cd59355404a5ecb0fc8d404ee15abada6c958\"}}",
"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\": \"559cd59355404a5ecb0fc8d404ee15abada6c958\", \"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-2dz0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17420, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"fd132e4e7a9c84333b4fb4730f79367fe75f53e7\", \"got_dawn_revision\": \"0b17eb8e19c886680b12b73ba28de8d30adccda9\", \"got_nacl_revision\": \"8a92e6ba1b6d62631f8d3ba3f4f8872ae8b6cca1\", \"got_revision\": \"559cd59355404a5ecb0fc8d404ee15abada6c958\", \"got_revision_cp\": \"refs/heads/master@{#855626}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"8603b126b3d599f0ab8781163729fd226a93cf17\", \"got_v8_revision\": \"aaf128851c4dd86978eaf9d41ce297c5af31597f\", \"got_v8_revision_cp\": \"refs/heads/9.0.217@{#1}\", \"got_webrtc_revision\": \"735e33fae0a06ae05a9c0be6f5d8b06a6ad820a4\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33297}\", \"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\": \"559cd59355404a5ecb0fc8d404ee15abada6c958\", \"swarm_hashes_refs/heads/master(at){#855626}_without_patch\": {\"absl_hardening_tests\": \"b231ad6f804c99449e572785decd16d66df2d68c\", \"accessibility_unittests\": \"60ee67e58eca25ec42efb474e92858656703977b\", \"angle_unittests\": \"e854788d32ad155afec15ba551e90392dccb8a65\", \"app_shell_unittests\": \"eb93cc4aa852618a7f94f1a4dea458d1c20e94ac\", \"aura_unittests\": \"ffb768c06a44b27ca57cf539c043e9cd37332092\", \"base_unittests\": \"09387e3575a6e9847a7b6e9bcd844e6177cefec8\", \"base_util_unittests\": \"19207a7d873cf0f74de523e8cd5a556204c5ee74\", \"blink_common_unittests\": \"84fa4f6037a706f4d6f3d30ae62d9dc4fb8d5ebe\", \"blink_fuzzer_unittests\": \"9c9df9262ecd700e6cab43bfbda4383611811847\", \"blink_heap_unittests\": \"07dff6f1ec688c32e4f5a43ec718786772cd377d\", \"blink_platform_unittests\": \"03d318f29ff1a2acbd6aa3c2b7a887f425952ebe\", \"blink_unittests\": \"68f4cd99de2847db01a71ddd639d21cf08bca22f\", \"boringssl_crypto_tests\": \"4bcfc204003ee0e1467ac45995efc3a9652eec61\", \"boringssl_ssl_tests\": \"8aae27632d89125332a043ddd0d48febedaddd5e\", \"browser_switcher_bho_unittests\": \"f6a103fc946fd1c603fc899d4d0d0fb2ed4b96a8\", \"browser_tests\": \"8f5623c33bfa39966aeeeefb73fde34eb4e0bfd2\", \"capture_unittests\": \"888ec9e702f7b0bdf24d64579ae98e738695569c\", \"cast_unittests\": \"e155949d4833c6ed924361ffe895abf772cd4ba0\", \"cc_unittests\": \"fead66858a8c04ab4b9c1d4b83729a86370e35e9\", \"chrome_app_unittests\": \"9e1fd3c14499f7cbec6f521c545404886e346454\", \"chrome_cleaner_unittests\": \"4031d56f41e8be64d0f99dd20c514d35e3c9503c\", \"chrome_elf_unittests\": \"2f40961e15b8f0b27a872050763ba53e83df942a\", \"chromedriver_unittests\": \"05312ef668e397d0ce7a74d8ca1147c76cae6ab1\", \"components_browsertests\": \"dd11c6eb421652630d2c8f5921dbb8838c6d51f2\", \"components_unittests\": \"9494f4c05fca1f81197f111052f700086873833f\", \"compositor_unittests\": \"28dee7c12d472b5c2286ce4c46aca245835aaca3\", \"content_browsertests\": \"92e38f9aad4f1d4a2abc4fa0dbc268a2c51623e7\", \"content_unittests\": \"2315b7aaee78094e2eb891430edbb6a6fb80416b\", \"courgette_unittests\": \"e44102befa4bab606cbae51659bf377b36db1e8d\", \"crashpad_tests\": \"e5c69e187df836a9fa9b104c2f0e7aa6b34eae89\", \"cronet_tests\": \"abdae9817e1ab14bb17a2164bbed7e702a55f7df\", \"cronet_unittests\": \"caa5da148d6c144b4108e56469586da70aad731d\", \"crypto_unittests\": \"d0a13231fc91f98c9e0a053733860b17e0b02af3\", \"delayloads_unittests\": \"2c7db3b20ddf71d796730d431f499f5a582e9aab\", \"device_unittests\": \"b37694eefa531afec9d2bb0aee977df9a37aed50\", \"display_unittests\": \"62c92a834e1869987fb4fa7817bff27ec81b7dab\", \"elevation_service_unittests\": \"214f28b1a1bc72fcf0d29cb7689037d689599841\", \"events_unittests\": \"0392375f5d3d3da1a1bdf5f2998724e4ced69bac\", \"extensions_browsertests\": \"6dbcdb9bbf0da09f4498e8e479dad449046103f9\", \"extensions_unittests\": \"c6e96c39ea68f044b1a24ca6cae9c472dec86178\", \"filesystem_service_unittests\": \"47139a4e7156f1c1bc5b6c31d4335c52e47f86b3\", \"gcm_unit_tests\": \"c96edde3935b53c07b0036f46f86b473ada3ff3a\", \"gcp_unittests\": \"9c05166cc854fd09bd3f00fe219de07a7a0ca81c\", \"gfx_unittests\": \"fcbbb1e970d47c6d2a88f5ccc9990d660cd3273b\", \"gin_unittests\": \"9492d96750c3332161492f40d1ed294bed96a597\", \"google_apis_unittests\": \"e37cb0daf41affa9d982585dadbfdb3d33cdb757\", \"gpu_unittests\": \"2c9081fb694c0d37011fc2dcb5ba06884e5df831\", \"gwp_asan_unittests\": \"21e4f89432cce3fe1060ad131aeb9fc3b8e5b7f6\", \"headless_browsertests\": \"c794b54ea9d0b5b4d17d88bb709d18627a306a5b\", \"headless_unittests\": \"d3f08832f47d6499a5f1e783649978dc7845e250\", \"install_static_unittests\": \"eafea178b272fd38622a0e2f2cf35287c52d6101\", \"installer_util_unittests\": \"19937cfa0d9fa63f3fbdb743fe9af21fb615b1de\", \"interactive_ui_tests\": \"66ce514111fbfcaa1de8cf8d040573355a87ea17\", \"ipc_tests\": \"f53e8cfe0ac48b3b87b2b6fbad83b9d294bf26b2\", \"jingle_unittests\": \"0aba435ac8c596332ceb3bb41e0805c3aa31c9fa\", \"latency_unittests\": \"f14039a0c2bad8b324cfe80e6684f957e6360240\", \"libjingle_xmpp_unittests\": \"6abdb074197364f6ff7f11360f1841d055afc895\", \"liburlpattern_unittests\": \"83ff19686cb07515adecfeaa805763ef51060df3\", \"media_blink_unittests\": \"73b9fb6bb4865d7f6a435c95a4be279666e9a32b\", \"media_unittests\": \"32f0aecef0bb8b250afd029488ec83db81412e8e\", \"message_center_unittests\": \"39f6bfcb1a4e6c3d349d59a1ed9cf4b2a4e4e416\", \"midi_unittests\": \"b8e6804ba55ef7f0b36b0bd4fbd665cdd9991aea\", \"mojo_core_unittests\": \"305929c8e3c17ca950ca253195275beda4336171\", \"mojo_unittests\": \"d5b8171ba258868296b355c586b5fdc7d98c2210\", \"nacl_loader_unittests\": \"e19bbad96cff6edb3eaf80e0d8c222bca93f27f4\", \"native_theme_unittests\": \"ffde3c6b154e5ac01cd1c83153d09b7117020808\", \"net_unittests\": \"8a7f45d3c5758464e0ecd41bfb7266b3f371cfa4\", \"notification_helper_unittests\": \"7e0bae53f47898906aa6641d05a69b8070c783ea\", \"pdf_unittests\": \"4fde1f1163d45cb0100593c8e6ce2f753827bdd8\", \"perfetto_unittests\": \"ff92accf5f425b56454b40b8d05ff79911020cb3\", \"ppapi_unittests\": \"442d685531e3d153f70eb297422d5e7ebdd4e5a0\", \"printing_unittests\": \"5b2c4870a8b1a80660a9d9a08a3e7a2de2651209\", \"remoting_unittests\": \"05f29f1cc470a662a5a740cbaeb990114d51d877\", \"sbox_integration_tests\": \"aebba90b7ba05f01b9163dbd486f80c38aa9a94d\", \"sbox_unittests\": \"f7ed35f5f8e7f1dd002fa973356dbe8f29c74224\", \"sbox_validation_tests\": \"4dd6c159dd46e04ef8790e1203e883d362831a22\", \"service_manager_unittests\": \"04eef3a694aebc4c135326f562c5924124d72a3b\", \"services_unittests\": \"bed03a811da0588fc9ef5032ca07659c04f0f9bc\", \"setup_unittests\": \"36df9597f6b12cb80deeb5856683501d206e3379\", \"shell_dialogs_unittests\": \"cf1c4c914653e4d4ab0e5a80e89b315889a74a00\", \"skia_unittests\": \"c98a8e7b4c29f0d49a067d3d9b6b1b52824acd26\", \"snapshot_unittests\": \"f86ee12ff801dcf4d3838c58abe3117f214d0d6d\", \"sql_unittests\": \"ccf13c0f4206481ec4144cf824c180e075d541ba\", \"storage_unittests\": \"424bd1891cccca8b9e691e0f1928210a95fcccfa\", \"sync_integration_tests\": \"da923d0366306d91198203bf53b20a15c91247e3\", \"traffic_annotation_auditor_unittests\": \"148abd817e0b49b779e06bd5a59e29eaf6d6bc31\", \"ui_base_unittests\": \"d016e8878a8eea48a42e6a426ee64e78d9f43990\", \"ui_touch_selection_unittests\": \"b96a33191077d529ad1c499ecf37e1ce791de202\", \"unit_tests\": \"6bfa2ae1ac6e000fd9d6ac9cc4cfdcab2458300a\", \"updater_tests\": \"77b063a9cf8c03a8dae0ad8297a3ff6a36670fdf\", \"url_unittests\": \"95e5471ce15210d81b94f4f79f6c83ea9d5f662f\", \"views_unittests\": \"6c38e2def954241fa0e0a037e5d39286a9a5b5fc\", \"viz_unittests\": \"53dca4e32abece1ae4686f2cbf3651554228ecbd\", \"vr_common_unittests\": \"537c5abc1ede3168c14194dfadb73545b5eb2bb6\", \"vr_pixeltests\": \"5b21cd14972e474dcdf9da2d96ce81bc64d0fe37\", \"weblayer_browsertests\": \"189699f394d600da5be1d0b3ba668f21e76f250e\", \"weblayer_unittests\": \"f7ed1f8a4148badc6db1b8bc7041f8f487293e2f\", \"wm_unittests\": \"2322185602748f0b1d68800ef0cbf7bf34b1ec84\", \"wtf_unittests\": \"0ca9420dc6d4fd55c0cf141c6cdc2fe6c337fb30\", \"zlib_unittests\": \"298551ea8aa008082d2a2f8f8d10070d44829dca\", \"zucchini_unittests\": \"f6e6bdc7f3d659771453f73b51d233eeb98095a7\"}}, \"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-1-2dz0\"], \"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\": [\"5706-397711c\"], \"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": "1613707815453881",
"status": "COMPLETED",
"status_changed_ts": "1613711401628070",
"tags": [
"build_address:luci.chromium.ci/win-asan/17420",
"builder:win-asan",
"buildset:commit/git/559cd59355404a5ecb0fc8d404ee15abada6c958",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/559cd59355404a5ecb0fc8d404ee15abada6c958",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020328495059967280",
"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/8854898432971457968/+/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:51d14cab18051b10",
"user_agent:luci-scheduler"
],
"updated_ts": "1613711401632372",
"url": "https://ci.chromium.org/b/8854898432971457968",
"utcnow_ts": "1613711405366837"
}
}
[05:10:05.376] Invocation finished in 59m54.454494341s with status SUCCEEDED