[09:14:36.901] New invocation is queued and will start shortly
[09:14:37.999] Starting the invocation (attempt 1)
[09:14:38.029] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:14:38.029] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:14:38.030] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9018769193380519312",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"8bf610d24ecc70cba93fcb492154908ff4a2b93e\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9018769193380519312",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/8bf610d24ecc70cba93fcb492154908ff4a2b93e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8bf610d24ecc70cba93fcb492154908ff4a2b93e",
"gitiles_ref:refs/heads/master"
]
}
[09:14:38.570] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1615194878103888",
"id": "8853339131456070320",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8bf610d24ecc70cba93fcb492154908ff4a2b93e\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1615194878446658",
"tags": [
"build_address:luci.chromium.ci/win-asan/17699",
"builder:win-asan",
"buildset:commit/git/8bf610d24ecc70cba93fcb492154908ff4a2b93e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8bf610d24ecc70cba93fcb492154908ff4a2b93e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018769193380519312",
"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/8853339131456070320/+/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": "1615194878446859",
"url": "https://ci.chromium.org/b/8853339131456070320",
"utcnow_ts": "1615194878565178"
}
}
[09:14:38.570] Task URL: https://ci.chromium.org/b/8853339131456070320
[09:14:38.570] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:2:0) after 1m0s
[09:14:45.204] Received PubSub notification, asking Buildbucket for the build status
[09:14:45.266] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:38.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:2:0)
[09:15:38.634] Timer tick, asking Buildbucket for the build status
[09:15:38.717] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:38.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:4:0) after 1m0s
[09:16:38.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:4:0)
[09:16:38.739] Timer tick, asking Buildbucket for the build status
[09:16:38.804] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:16:38.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:5:0) after 1m0s
[09:17:38.877] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:5:0)
[09:17:38.877] Timer tick, asking Buildbucket for the build status
[09:17:38.974] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:17:38.974] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:6:0) after 1m0s
[09:18:39.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:6:0)
[09:18:39.105] Timer tick, asking Buildbucket for the build status
[09:18:39.171] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:18:39.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:7:0) after 1m0s
[09:19:39.404] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:7:0)
[09:19:39.404] Timer tick, asking Buildbucket for the build status
[09:19:39.732] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:19:39.732] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:8:0) after 1m0s
[09:20:39.708] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:8:0)
[09:20:39.708] Timer tick, asking Buildbucket for the build status
[09:20:39.866] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:20:39.866] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:9:0) after 1m0s
[09:21:39.919] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:9:0)
[09:21:39.919] Timer tick, asking Buildbucket for the build status
[09:21:40.036] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:21:40.036] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:10:0) after 1m0s
[09:22:40.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:10:0)
[09:22:40.295] Timer tick, asking Buildbucket for the build status
[09:22:40.365] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:22:40.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:11:0) after 1m0s
[09:23:40.494] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:11:0)
[09:23:40.495] Timer tick, asking Buildbucket for the build status
[09:23:40.583] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:23:40.583] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:12:0) after 1m0s
[09:24:40.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:12:0)
[09:24:40.780] Timer tick, asking Buildbucket for the build status
[09:24:40.854] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:24:40.854] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:13:0) after 1m0s
[09:25:41.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:13:0)
[09:25:41.101] Timer tick, asking Buildbucket for the build status
[09:25:41.232] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:25:41.232] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:14:0) after 1m0s
[09:26:41.227] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:14:0)
[09:26:41.227] Timer tick, asking Buildbucket for the build status
[09:26:41.291] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:26:41.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:15:0) after 1m0s
[09:27:41.429] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:15:0)
[09:27:41.429] Timer tick, asking Buildbucket for the build status
[09:27:41.726] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:27:41.726] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:16:0) after 1m0s
[09:28:42.113] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:16:0)
[09:28:42.113] Timer tick, asking Buildbucket for the build status
[09:28:42.176] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:28:42.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:17:0) after 1m0s
[09:29:42.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:17:0)
[09:29:42.228] Timer tick, asking Buildbucket for the build status
[09:29:42.645] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:29:42.645] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:18:0) after 1m0s
[09:30:42.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:18:0)
[09:30:42.836] Timer tick, asking Buildbucket for the build status
[09:30:43.222] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:30:43.222] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:19:0) after 1m0s
[09:31:43.328] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:19:0)
[09:31:43.328] Timer tick, asking Buildbucket for the build status
[09:31:43.437] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:31:43.437] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:20:0) after 1m0s
[09:32:43.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:20:0)
[09:32:43.660] Timer tick, asking Buildbucket for the build status
[09:32:43.888] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:32:43.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:21:0) after 1m0s
[09:33:44.028] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:21:0)
[09:33:44.028] Timer tick, asking Buildbucket for the build status
[09:33:44.119] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:33:44.119] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:22:0) after 1m0s
[09:34:44.173] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:22:0)
[09:34:44.173] Timer tick, asking Buildbucket for the build status
[09:34:44.321] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:34:44.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:23:0) after 1m0s
[09:35:44.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:23:0)
[09:35:44.354] Timer tick, asking Buildbucket for the build status
[09:35:44.679] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:35:44.680] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:24:0) after 1m0s
[09:36:44.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:24:0)
[09:36:44.748] Timer tick, asking Buildbucket for the build status
[09:36:48.791] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:36:48.791] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:25:0) after 1m0s
[09:37:48.901] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:25:0)
[09:37:48.901] Timer tick, asking Buildbucket for the build status
[09:37:48.988] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:37:48.988] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:26:0) after 1m0s
[09:38:49.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:26:0)
[09:38:49.214] Timer tick, asking Buildbucket for the build status
[09:38:49.306] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:38:49.306] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:27:0) after 1m0s
[09:39:49.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:27:0)
[09:39:49.602] Timer tick, asking Buildbucket for the build status
[09:39:49.694] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:39:49.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:28:0) after 1m0s
[09:40:49.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:28:0)
[09:40:49.752] Timer tick, asking Buildbucket for the build status
[09:40:50.235] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:40:50.235] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:29:0) after 1m0s
[09:41:50.371] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:29:0)
[09:41:50.371] Timer tick, asking Buildbucket for the build status
[09:41:50.509] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:41:50.509] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:30:0) after 1m0s
[09:42:50.587] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:30:0)
[09:42:50.588] Timer tick, asking Buildbucket for the build status
[09:42:50.661] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:42:50.661] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:31:0) after 1m0s
[09:43:50.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:31:0)
[09:43:50.740] Timer tick, asking Buildbucket for the build status
[09:43:51.104] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:43:51.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:32:0) after 1m0s
[09:44:51.194] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:32:0)
[09:44:51.194] Timer tick, asking Buildbucket for the build status
[09:44:51.438] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:44:51.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:33:0) after 1m0s
[09:45:51.487] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:33:0)
[09:45:51.487] Timer tick, asking Buildbucket for the build status
[09:45:51.611] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:45:51.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:34:0) after 1m0s
[09:46:51.703] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:34:0)
[09:46:51.703] Timer tick, asking Buildbucket for the build status
[09:46:52.275] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:46:52.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:35:0) after 1m0s
[09:47:52.309] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:35:0)
[09:47:52.309] Timer tick, asking Buildbucket for the build status
[09:47:52.393] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:47:52.393] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:36:0) after 1m0s
[09:48:52.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:36:0)
[09:48:52.438] Timer tick, asking Buildbucket for the build status
[09:48:52.549] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:48:52.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:37:0) after 1m0s
[09:49:52.583] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:37:0)
[09:49:52.583] Timer tick, asking Buildbucket for the build status
[09:49:52.665] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:49:52.665] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:38:0) after 1m0s
[09:50:52.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:38:0)
[09:50:52.683] Timer tick, asking Buildbucket for the build status
[09:50:52.938] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:50:52.938] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:39:0) after 1m0s
[09:51:52.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:39:0)
[09:51:52.941] Timer tick, asking Buildbucket for the build status
[09:51:53.014] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:51:53.014] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:40:0) after 1m0s
[09:52:52.988] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:40:0)
[09:52:52.988] Timer tick, asking Buildbucket for the build status
[09:52:53.128] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:52:53.128] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:41:0) after 1m0s
[09:53:53.144] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:41:0)
[09:53:53.144] Timer tick, asking Buildbucket for the build status
[09:53:53.231] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:53:53.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:42:0) after 1m0s
[09:54:53.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:42:0)
[09:54:53.249] Timer tick, asking Buildbucket for the build status
[09:54:53.356] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:54:53.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:43:0) after 1m0s
[09:55:54.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:43:0)
[09:55:54.288] Timer tick, asking Buildbucket for the build status
[09:55:54.355] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:55:54.355] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:44:0) after 1m0s
[09:56:54.492] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:44:0)
[09:56:54.492] Timer tick, asking Buildbucket for the build status
[09:56:54.591] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:56:54.591] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:45:0) after 1m0s
[09:57:54.826] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:45:0)
[09:57:54.826] Timer tick, asking Buildbucket for the build status
[09:57:54.893] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:57:54.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:46:0) after 1m0s
[09:58:55.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:46:0)
[09:58:55.089] Timer tick, asking Buildbucket for the build status
[09:58:55.289] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:58:55.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:47:0) after 1m0s
[09:59:55.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:47:0)
[09:59:55.347] Timer tick, asking Buildbucket for the build status
[09:59:55.434] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:59:55.434] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:48:0) after 1m0s
[10:00:55.499] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:48:0)
[10:00:55.499] Timer tick, asking Buildbucket for the build status
[10:00:55.597] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:00:55.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:49:0) after 1m0s
[10:01:55.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:49:0)
[10:01:55.740] Timer tick, asking Buildbucket for the build status
[10:01:55.844] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:01:55.844] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:50:0) after 1m0s
[10:02:55.861] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:50:0)
[10:02:55.861] Timer tick, asking Buildbucket for the build status
[10:02:55.960] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:02:55.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:51:0) after 1m0s
[10:03:55.958] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:51:0)
[10:03:55.958] Timer tick, asking Buildbucket for the build status
[10:03:56.025] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:03:56.025] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:52:0) after 1m0s
[10:04:56.087] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:52:0)
[10:04:56.087] Timer tick, asking Buildbucket for the build status
[10:04:56.353] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:04:56.353] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:53:0) after 1m0s
[10:05:56.381] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:53:0)
[10:05:56.381] Timer tick, asking Buildbucket for the build status
[10:05:56.506] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:05:56.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:54:0) after 1m0s
[10:06:56.559] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:54:0)
[10:06:56.559] Timer tick, asking Buildbucket for the build status
[10:06:56.631] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:06:56.631] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:55:0) after 1m0s
[10:07:56.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:55:0)
[10:07:56.736] Timer tick, asking Buildbucket for the build status
[10:07:57.123] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:07:57.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:56:0) after 1m0s
[10:08:57.245] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:56:0)
[10:08:57.245] Timer tick, asking Buildbucket for the build status
[10:08:57.317] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:08:57.317] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:57:0) after 1m0s
[10:09:57.350] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:57:0)
[10:09:57.350] Timer tick, asking Buildbucket for the build status
[10:09:57.660] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:09:57.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:58:0) after 1m0s
[10:10:57.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:58:0)
[10:10:57.711] Timer tick, asking Buildbucket for the build status
[10:10:58.142] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:10:58.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:59:0) after 1m0s
[10:11:58.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:59:0)
[10:11:58.280] Timer tick, asking Buildbucket for the build status
[10:12:00.155] Build 8853339131456070320: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:12:00.155] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9018769193380519312:60:0) after 1m0s
[10:12:29.610] Received PubSub notification, asking Buildbucket for the build status
[10:12:29.839] Build 8853339131456070320: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[10:12:29.839] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1615198348952206",
"created_by": "project:chromium",
"created_ts": "1615194878103888",
"id": "8853339131456070320",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"8bf610d24ecc70cba93fcb492154908ff4a2b93e\"}}",
"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\": \"8bf610d24ecc70cba93fcb492154908ff4a2b93e\", \"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-8-2xaw\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17699, \"clang_revision\": \"llvmorg-13-init-3462-gfe5c2c3c-1\", \"got_angle_revision\": \"9bc86c50f283560a3294c256bacccc1accb5bc94\", \"got_dawn_revision\": \"010fd1a8d7c7dd4e8fabe73546be78c45b6419c2\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"8bf610d24ecc70cba93fcb492154908ff4a2b93e\", \"got_revision_cp\": \"refs/heads/master@{#860658}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"de7d5775ab25234a2c14d061e0cda342248a0e6d\", \"got_v8_revision\": \"f8b883fbba96b155409eb00da93826216bea9fc0\", \"got_v8_revision_cp\": \"refs/heads/9.1.60@{#1}\", \"got_webrtc_revision\": \"3208bf102b75e8ec1eaa6640dfa3b545310b1e02\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33382}\", \"is_cached\": true, \"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\": \"8bf610d24ecc70cba93fcb492154908ff4a2b93e\", \"swarm_hashes_refs/heads/master(at){#860658}_without_patch\": {\"absl_hardening_tests\": \"d4839ba9ba86aa9ffadb155ff50ff33ad1c9c273\", \"accessibility_unittests\": \"f4ee984c16a69a232c240b20cc6182aad2881911\", \"angle_unittests\": \"75f9bc528c302ac620423c70f3150893ba7ed744\", \"app_shell_unittests\": \"b15c7bba7db8d0ab9bc2da4c7ec920483f5b8f80\", \"aura_unittests\": \"828152459350d80f57c74d2772f090b704fefc06\", \"base_unittests\": \"7d80f301d07762f0e925b08fd1c431fd476d1c77\", \"base_util_unittests\": \"6c7aff3a502fbe72fe994697271be85155c44887\", \"blink_common_unittests\": \"98bcec90f3a43c4f01290d18f2cc549c592c66c6\", \"blink_fuzzer_unittests\": \"429bf1ef4902d860d88fabfef7537726b4543d54\", \"blink_heap_unittests\": \"70b37b48a840a4e56228b997ce4959c5bc3b3cff\", \"blink_platform_unittests\": \"9ef65af5df27e6f485d08747fc018ae03ccae1be\", \"blink_unittests\": \"8cc3f23a8cef8903e10a29c457e643a723225371\", \"boringssl_crypto_tests\": \"2a54773cc316a3fed584710b7273f5223b1da399\", \"boringssl_ssl_tests\": \"90202be014d41cfbac1d84ca0b7e1b6b14a02286\", \"browser_switcher_bho_unittests\": \"529d8dd3a3b0c3b32ee3c0de2b753ccd52096b5c\", \"browser_tests\": \"9910f1337a18a852a744f1bfdb738ef2a25edda7\", \"capture_unittests\": \"1f1da72cc610a605d4b78e2426174c60875d0f9f\", \"cast_unittests\": \"4bdd245638db57d0648ed5e678ea419b0c2f0b01\", \"cc_unittests\": \"b183ce2624056d8fa63a083417056aadf830d297\", \"chrome_app_unittests\": \"ec11de7667ade855f567da4fceb9fc1e8033ba4a\", \"chrome_cleaner_unittests\": \"9c51146347a18af3988a3f1b631e5dff6727a70e\", \"chrome_elf_unittests\": \"b9cc7edee8388fe30140798a8c6191d5b5a20aef\", \"chromedriver_unittests\": \"bd7000fa06f3118eaaaeb4ad594ab0551c95efa5\", \"color_unittests\": \"93adf28313ebccea6d7c1bdc760ade24925841b5\", \"components_browsertests\": \"c5696eefa4f8028cedf7750f216569a25d34b6ea\", \"components_unittests\": \"fcb7efc17c97b5984de7911fbe1b4ab8d581e666\", \"compositor_unittests\": \"b964789c98b9eca37897786db0cc3b0f29e6f1d9\", \"content_browsertests\": \"56ced50922d526502c781a5d58ec530b9b1e4af4\", \"content_unittests\": \"fa463649516550e77bfaf7b7a75e695381b52446\", \"courgette_unittests\": \"453de8095e9ebf6ab3ff0c4ae5c27ff16ce89cf2\", \"crashpad_tests\": \"9777fe37a2c0f60e8fe2d80b331cd74544c4c20b\", \"cronet_tests\": \"0f1bab4a7e27e32c69e0797ec4741a67242b9447\", \"cronet_unittests\": \"3781c68f70f6944a330965b606af3353f79d422a\", \"crypto_unittests\": \"d41e10803caa055afe2d8c898d999ad308dcdec7\", \"delayloads_unittests\": \"262bef71c39da19dc0af091c0f92341be0e1ba8a\", \"device_unittests\": \"d070c4f099e9e1aca5b797d54beb568ff3b54616\", \"display_unittests\": \"e42fd6f153c2a627fee9f5f8f0c2befa510e974e\", \"elevation_service_unittests\": \"7e4be7bda9db6e04b386c9961663e00a756815c3\", \"events_unittests\": \"3a186fe321abdff1e04a0732e76bd5d054b8c511\", \"extensions_browsertests\": \"9adb7036afba909a78483205bd6985944d3fc327\", \"extensions_unittests\": \"ff63d0e6c9eeee8d26f23acb299bb14a7691b752\", \"filesystem_service_unittests\": \"d3222b0d2b8ebf89e1c9fde6dd1aaf6597604c32\", \"gcm_unit_tests\": \"a9e6bfbeb33266d6166ef26dc41db379f8b3a81b\", \"gcp_unittests\": \"90ea4c06e711c151f64e20f3ad08cacefd3b63b4\", \"gfx_unittests\": \"1dd7564f4a6d326a84a0451fcbd3cc7e2a1c7440\", \"gin_unittests\": \"205708feeac91f3b9453147ffb2df669e3e5973d\", \"google_apis_unittests\": \"88ff0bd5fa0f678ac2209d2c78fd9ab6cb572c92\", \"gpu_unittests\": \"99f015c6cc9c2fae6753b560051b4dae2d5bb2e6\", \"gwp_asan_unittests\": \"91f6d5380ef34bca005ed20808e0c31e3ee771af\", \"headless_browsertests\": \"cb7449cd73f27b8a9f3462a94c7d68509a614bd6\", \"headless_unittests\": \"710e5541a98b72e0f99332026348810e8434df46\", \"install_static_unittests\": \"1a0c5695fc9b32889281a95cfc641ab1bf07d641\", \"installer_util_unittests\": \"8590791e7aaf5057354ab9ac18c3b248931113de\", \"interactive_ui_tests\": \"440e88f79f91f9dc264541c4b82c56bfb4479a5e\", \"ipc_tests\": \"067a52d165efca269b360fbe1bd8b1e740ea8811\", \"jingle_unittests\": \"b4267e7d57d3155adab40accfedbcbd2d131d73b\", \"latency_unittests\": \"0cd31ed5b708694f9dbeb55a8566ba6cb049a44e\", \"libjingle_xmpp_unittests\": \"c456698b7c143598953d02e550b1bbb47f3452b3\", \"liburlpattern_unittests\": \"e0603e4eabf5056a04ccb5fc4cfe148534046ecb\", \"media_blink_unittests\": \"319f90eb4790840e8030b64db25439c496ffeafa\", \"media_unittests\": \"fd970733ceb375ee8cd751cd2abb069b14764c52\", \"message_center_unittests\": \"f005093ff703dcc35db26c913ccfd6adf60bbc86\", \"midi_unittests\": \"703fbe32c825fbd92a5921c434be721151dfe4b9\", \"mojo_core_unittests\": \"0195c8fe41db7da16fe5f27046763f6a17dcc7d3\", \"mojo_unittests\": \"f850f08445d04c6ef199e1b859fc2491360d7bd6\", \"nacl_loader_unittests\": \"391d89fd50bec66007a5dbde12d6ef0947251c15\", \"native_theme_unittests\": \"4b22627e62f87b31237525a8d1b57b67bb302366\", \"net_unittests\": \"6a03498d68f9946d26acd039fdaa4cb8e90a02fd\", \"notification_helper_unittests\": \"65f60cecaa6859448b00f06ea34658a2083f42d6\", \"pdf_unittests\": \"622ef0fd9f28afe16549ee7c46a69e77ca40dec0\", \"perfetto_unittests\": \"87cadc504bb74f94c5d3fb2b874e8ea3b0eaf945\", \"ppapi_unittests\": \"378fe488fdcbe34a89eba6c54d87e9abf95ef7e0\", \"printing_unittests\": \"038e27ed159d01fec3e0522b75d38795d32d2f3a\", \"remoting_unittests\": \"2a958475f7aaa8a0d4705d03653c8649e7cae882\", \"sbox_integration_tests\": \"0c22f9887979fc514e1b4e953b33701666b241de\", \"sbox_unittests\": \"020e1ba2a12d67758e03088ad717d51a469d5c86\", \"sbox_validation_tests\": \"768e021832eaa38bba256529e61321f040e11ac7\", \"service_manager_unittests\": \"ed8caca6c3b519a2c33b7006f84c0b14664d97d6\", \"services_unittests\": \"a3707cab59a27ba31298a4ed1c521127cbe96d78\", \"setup_unittests\": \"f100619cb77962f2ce13e21efbf7e583297515d8\", \"shell_dialogs_unittests\": \"7fe89c4482f49b76ef058f5d0e5498205652f89e\", \"skia_unittests\": \"26aa25f77f0dee811a4fc872c28cd72fa8466ea7\", \"snapshot_unittests\": \"03367212ccd445fe60b9083d4c9e23530b95d68c\", \"sql_unittests\": \"7ea02dd40ad27d26abe661d9fbe41056d238e2c4\", \"storage_unittests\": \"33c0c92b98f50fd5fa2105e84f90cf0be780c814\", \"sync_integration_tests\": \"5ca18dd1503feb11df065026095d3135341df94f\", \"traffic_annotation_auditor_unittests\": \"c1b3f37593321a6d777be72a9b71f0554fd2df29\", \"ui_base_unittests\": \"679d80985f63ceb3ca4370ee61159d5126957a33\", \"ui_touch_selection_unittests\": \"78afe950c5a06704f2e6075c76a93f67940b8b33\", \"unit_tests\": \"f2a7cba6ff4b01ed67b92771afc963fbd1493c7f\", \"updater_tests\": \"d1ae64688ae79619852a37b789a074407f197bf6\", \"url_unittests\": \"ae5c8f19552eca2df39f13252b6b62ebcb138fc7\", \"views_unittests\": \"b5e767065b55f98174ae49d9baecd188bf0be7cd\", \"viz_unittests\": \"2c74eed9be17d1dc2d80b815978c2fcb5554aaf0\", \"vr_common_unittests\": \"fa7da291267eccdc5349b975ae871fbb3200b8d4\", \"vr_pixeltests\": \"ff1a0cf1e11119acc920dfab14c7e82b4cf09e49\", \"weblayer_browsertests\": \"c4c1f99fbc5f3c63b29e677c782863214d4c3478\", \"weblayer_unittests\": \"1e4b0011d5c5c0d8e23d9d0ac7355693a6fad34f\", \"wm_unittests\": \"6327b4452d9293c0f9a08fbb59a5edfbb479503b\", \"wtf_unittests\": \"b66d81e9c287faa4e384ba1391faabe6c5ee96dd\", \"zlib_unittests\": \"ee0bbbcd7952f3ab36bc94006a1536ca4e741575\", \"zucchini_unittests\": \"245349eaafcab3ca1afee1bfc7607b23fc73e1ac\"}}, \"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-8-2xaw\"], \"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\": [\"5744-5c0a4b4\"], \"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": "1615194884636539",
"status": "COMPLETED",
"status_changed_ts": "1615198349328488",
"tags": [
"build_address:luci.chromium.ci/win-asan/17699",
"builder:win-asan",
"buildset:commit/git/8bf610d24ecc70cba93fcb492154908ff4a2b93e",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/8bf610d24ecc70cba93fcb492154908ff4a2b93e",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9018769193380519312",
"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/8853339131456070320/+/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:5229ef7ac5ae4a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1615198349333057",
"url": "https://ci.chromium.org/b/8853339131456070320",
"utcnow_ts": "1615198349828648"
}
}
[10:12:29.839] Invocation finished in 57m52.954463339s with status SUCCEEDED