[21:28:02.557] New invocation is queued and will start shortly
[21:28:03.644] Starting the invocation (attempt 1)
[21:28:03.686] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:28:03.686] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:28:03.686] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9024068271126314384",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"9cb18c14c551a56f66c9ecf61cebd978d0d9073a\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9024068271126314384",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/9cb18c14c551a56f66c9ecf61cebd978d0d9073a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9cb18c14c551a56f66c9ecf61cebd978d0d9073a",
"gitiles_ref:refs/heads/master"
]
}
[21:28:04.495] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610141283814642",
"id": "8858638209142216544",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9cb18c14c551a56f66c9ecf61cebd978d0d9073a\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610141284347435",
"tags": [
"build_address:luci.chromium.ci/win-asan/16700",
"builder:win-asan",
"buildset:commit/git/9cb18c14c551a56f66c9ecf61cebd978d0d9073a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9cb18c14c551a56f66c9ecf61cebd978d0d9073a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024068271126314384",
"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/8858638209142216544/+/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": "1610141284347579",
"url": "https://ci.chromium.org/b/8858638209142216544",
"utcnow_ts": "1610141284484694"
}
}
[21:28:04.495] Task URL: https://ci.chromium.org/b/8858638209142216544
[21:28:04.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:2:0) after 1m0s
[21:28:07.335] Received PubSub notification, asking Buildbucket for the build status
[21:28:07.434] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:04.567] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:2:0)
[21:29:04.567] Timer tick, asking Buildbucket for the build status
[21:29:04.667] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:29:04.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:4:0) after 1m0s
[21:30:04.793] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:4:0)
[21:30:04.793] Timer tick, asking Buildbucket for the build status
[21:30:04.926] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:30:04.926] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:5:0) after 1m0s
[21:31:05.001] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:5:0)
[21:31:05.001] Timer tick, asking Buildbucket for the build status
[21:31:05.123] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:31:05.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:6:0) after 1m0s
[21:32:05.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:6:0)
[21:32:05.218] Timer tick, asking Buildbucket for the build status
[21:32:05.320] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:32:05.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:7:0) after 1m0s
[21:33:05.386] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:7:0)
[21:33:05.386] Timer tick, asking Buildbucket for the build status
[21:33:05.479] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:33:05.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:8:0) after 1m0s
[21:34:05.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:8:0)
[21:34:05.527] Timer tick, asking Buildbucket for the build status
[21:34:06.354] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:34:06.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:9:0) after 1m0s
[21:35:06.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:9:0)
[21:35:06.527] Timer tick, asking Buildbucket for the build status
[21:35:06.717] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:35:06.717] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:10:0) after 1m0s
[21:36:06.751] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:10:0)
[21:36:06.751] Timer tick, asking Buildbucket for the build status
[21:36:06.832] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:36:06.832] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:11:0) after 1m0s
[21:37:06.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:11:0)
[21:37:06.888] Timer tick, asking Buildbucket for the build status
[21:37:06.953] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:37:06.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:12:0) after 1m0s
[21:38:07.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:12:0)
[21:38:07.063] Timer tick, asking Buildbucket for the build status
[21:38:07.209] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:38:07.209] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:13:0) after 1m0s
[21:39:07.335] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:13:0)
[21:39:07.335] Timer tick, asking Buildbucket for the build status
[21:39:07.403] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:39:07.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:14:0) after 1m0s
[21:40:07.511] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:14:0)
[21:40:07.511] Timer tick, asking Buildbucket for the build status
[21:40:07.716] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:40:07.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:15:0) after 1m0s
[21:41:07.816] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:15:0)
[21:41:07.816] Timer tick, asking Buildbucket for the build status
[21:41:07.877] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:41:07.877] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:16:0) after 1m0s
[21:42:07.987] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:16:0)
[21:42:07.987] Timer tick, asking Buildbucket for the build status
[21:42:08.173] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:42:08.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:17:0) after 1m0s
[21:43:08.251] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:17:0)
[21:43:08.251] Timer tick, asking Buildbucket for the build status
[21:43:08.797] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:43:08.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:18:0) after 1m0s
[21:44:08.852] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:18:0)
[21:44:08.852] Timer tick, asking Buildbucket for the build status
[21:44:08.937] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:44:08.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:19:0) after 1m0s
[21:45:09.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:19:0)
[21:45:09.062] Timer tick, asking Buildbucket for the build status
[21:45:09.227] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:45:09.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:20:0) after 1m0s
[21:46:09.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:20:0)
[21:46:09.322] Timer tick, asking Buildbucket for the build status
[21:46:09.636] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:46:09.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:21:0) after 1m0s
[21:47:09.707] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:21:0)
[21:47:09.707] Timer tick, asking Buildbucket for the build status
[21:47:09.786] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:47:09.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:22:0) after 1m0s
[21:48:09.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:22:0)
[21:48:09.763] Timer tick, asking Buildbucket for the build status
[21:48:09.923] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:48:09.923] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:23:0) after 1m0s
[21:49:09.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:23:0)
[21:49:09.981] Timer tick, asking Buildbucket for the build status
[21:49:10.124] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:49:10.124] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:24:0) after 1m0s
[21:50:10.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:24:0)
[21:50:10.232] Timer tick, asking Buildbucket for the build status
[21:50:10.312] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:50:10.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:25:0) after 1m0s
[21:51:10.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:25:0)
[21:51:10.399] Timer tick, asking Buildbucket for the build status
[21:51:10.494] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:51:10.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:26:0) after 1m0s
[21:52:10.624] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:26:0)
[21:52:10.624] Timer tick, asking Buildbucket for the build status
[21:52:10.748] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:52:10.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:27:0) after 1m0s
[21:53:10.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:27:0)
[21:53:10.822] Timer tick, asking Buildbucket for the build status
[21:53:10.975] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:53:10.975] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:28:0) after 1m0s
[21:54:11.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:28:0)
[21:54:11.026] Timer tick, asking Buildbucket for the build status
[21:54:11.169] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:54:11.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:29:0) after 1m0s
[21:55:11.393] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:29:0)
[21:55:11.393] Timer tick, asking Buildbucket for the build status
[21:55:11.710] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:55:11.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:30:0) after 1m0s
[21:56:11.713] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:30:0)
[21:56:11.713] Timer tick, asking Buildbucket for the build status
[21:56:11.853] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:56:11.853] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:31:0) after 1m0s
[21:57:11.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:31:0)
[21:57:11.947] Timer tick, asking Buildbucket for the build status
[21:57:12.082] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:57:12.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:32:0) after 1m0s
[21:58:12.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:32:0)
[21:58:12.165] Timer tick, asking Buildbucket for the build status
[21:58:12.282] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:58:12.282] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:33:0) after 1m0s
[21:59:12.320] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:33:0)
[21:59:12.320] Timer tick, asking Buildbucket for the build status
[21:59:12.444] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[21:59:12.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:34:0) after 1m0s
[22:00:12.493] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:34:0)
[22:00:12.493] Timer tick, asking Buildbucket for the build status
[22:00:12.991] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:00:12.991] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:35:0) after 1m0s
[22:01:13.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:35:0)
[22:01:13.008] Timer tick, asking Buildbucket for the build status
[22:01:13.248] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:01:13.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:36:0) after 1m0s
[22:02:13.347] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:36:0)
[22:02:13.347] Timer tick, asking Buildbucket for the build status
[22:02:13.604] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:02:13.604] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:37:0) after 1m0s
[22:03:13.631] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:37:0)
[22:03:13.631] Timer tick, asking Buildbucket for the build status
[22:03:14.183] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:03:14.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:38:0) after 1m0s
[22:04:14.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:38:0)
[22:04:14.206] Timer tick, asking Buildbucket for the build status
[22:04:14.554] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:04:14.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:39:0) after 1m0s
[22:05:14.584] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:39:0)
[22:05:14.584] Timer tick, asking Buildbucket for the build status
[22:05:14.667] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:05:14.667] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:40:0) after 1m0s
[22:06:14.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:40:0)
[22:06:14.644] Timer tick, asking Buildbucket for the build status
[22:06:14.852] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:06:14.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:41:0) after 1m0s
[22:07:14.909] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:41:0)
[22:07:14.909] Timer tick, asking Buildbucket for the build status
[22:07:15.071] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:07:15.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:42:0) after 1m0s
[22:08:15.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:42:0)
[22:08:15.073] Timer tick, asking Buildbucket for the build status
[22:08:15.436] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:08:15.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:43:0) after 1m0s
[22:09:15.553] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:43:0)
[22:09:15.553] Timer tick, asking Buildbucket for the build status
[22:09:15.718] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:09:15.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:44:0) after 1m0s
[22:10:15.736] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:44:0)
[22:10:15.736] Timer tick, asking Buildbucket for the build status
[22:10:15.947] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:10:15.947] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:45:0) after 1m0s
[22:11:16.063] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:45:0)
[22:11:16.063] Timer tick, asking Buildbucket for the build status
[22:11:16.176] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:11:16.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:46:0) after 1m0s
[22:12:16.247] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:46:0)
[22:12:16.247] Timer tick, asking Buildbucket for the build status
[22:12:16.354] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:12:16.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:47:0) after 1m0s
[22:13:16.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:47:0)
[22:13:16.411] Timer tick, asking Buildbucket for the build status
[22:13:16.565] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:13:16.565] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:48:0) after 1m0s
[22:14:16.674] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:48:0)
[22:14:16.674] Timer tick, asking Buildbucket for the build status
[22:14:16.760] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:14:16.760] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:49:0) after 1m0s
[22:15:16.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:49:0)
[22:15:16.833] Timer tick, asking Buildbucket for the build status
[22:15:16.924] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:15:16.924] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:50:0) after 1m0s
[22:16:16.938] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:50:0)
[22:16:16.938] Timer tick, asking Buildbucket for the build status
[22:16:17.467] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:16:17.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:51:0) after 1m0s
[22:17:17.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:51:0)
[22:17:17.462] Timer tick, asking Buildbucket for the build status
[22:17:17.636] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:17:17.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:52:0) after 1m0s
[22:18:17.682] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:52:0)
[22:18:17.682] Timer tick, asking Buildbucket for the build status
[22:18:17.817] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:18:17.817] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:53:0) after 1m0s
[22:19:17.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:53:0)
[22:19:17.896] Timer tick, asking Buildbucket for the build status
[22:19:18.024] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:19:18.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:54:0) after 1m0s
[22:20:18.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:54:0)
[22:20:18.046] Timer tick, asking Buildbucket for the build status
[22:20:18.152] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:20:18.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:55:0) after 1m0s
[22:21:18.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:55:0)
[22:21:18.204] Timer tick, asking Buildbucket for the build status
[22:21:18.298] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:21:18.298] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:56:0) after 1m0s
[22:22:18.326] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:56:0)
[22:22:18.326] Timer tick, asking Buildbucket for the build status
[22:22:18.620] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:22:18.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:57:0) after 1m0s
[22:23:18.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:57:0)
[22:23:18.644] Timer tick, asking Buildbucket for the build status
[22:23:18.795] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:23:18.795] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:58:0) after 1m0s
[22:24:18.820] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:58:0)
[22:24:18.820] Timer tick, asking Buildbucket for the build status
[22:24:18.972] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:24:18.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:59:0) after 1m0s
[22:25:18.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:59:0)
[22:25:18.976] Timer tick, asking Buildbucket for the build status
[22:25:19.191] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:25:19.191] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:60:0) after 1m0s
[22:26:19.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:60:0)
[22:26:19.253] Timer tick, asking Buildbucket for the build status
[22:26:19.515] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:26:19.515] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:61:0) after 1m0s
[22:27:19.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:61:0)
[22:27:19.621] Timer tick, asking Buildbucket for the build status
[22:27:19.767] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:27:19.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:62:0) after 1m0s
[22:28:19.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:62:0)
[22:28:19.766] Timer tick, asking Buildbucket for the build status
[22:28:19.919] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:28:19.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:63:0) after 1m0s
[22:29:19.950] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:63:0)
[22:29:19.950] Timer tick, asking Buildbucket for the build status
[22:29:20.047] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:29:20.047] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:64:0) after 1m0s
[22:30:20.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:64:0)
[22:30:20.115] Timer tick, asking Buildbucket for the build status
[22:30:20.207] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:30:20.207] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:65:0) after 1m0s
[22:31:20.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:65:0)
[22:31:20.249] Timer tick, asking Buildbucket for the build status
[22:31:20.336] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:31:20.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:66:0) after 1m0s
[22:32:20.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:66:0)
[22:32:20.407] Timer tick, asking Buildbucket for the build status
[22:32:20.472] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:32:20.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:67:0) after 1m0s
[22:33:20.595] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:67:0)
[22:33:20.595] Timer tick, asking Buildbucket for the build status
[22:33:20.729] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:33:20.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:68:0) after 1m0s
[22:34:20.768] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:68:0)
[22:34:20.768] Timer tick, asking Buildbucket for the build status
[22:34:20.935] Build 8858638209142216544: status "STARTED", result "", failure_reason "", cancelation_reason ""
[22:34:20.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9024068271126314384:69:0) after 1m0s
[22:34:47.209] Received PubSub notification, asking Buildbucket for the build status
[22:34:47.286] Build 8858638209142216544: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[22:34:47.286] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610145284696203",
"created_by": "project:chromium",
"created_ts": "1610141283814642",
"id": "8858638209142216544",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"9cb18c14c551a56f66c9ecf61cebd978d0d9073a\"}}",
"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\": \"9cb18c14c551a56f66c9ecf61cebd978d0d9073a\", \"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-w73f\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16700, \"clang_revision\": \"llvmorg-12-init-15676-g5c951623-1\", \"got_angle_revision\": \"fa449cf3b0d00e411a47039408fd31389d79beb0\", \"got_dawn_revision\": \"c4efeef8333435ef770fdf72f3ffdca9d7d19bfc\", \"got_nacl_revision\": \"d28e6c52e05ea2f1b3231de473b6dbfce59bce40\", \"got_revision\": \"9cb18c14c551a56f66c9ecf61cebd978d0d9073a\", \"got_revision_cp\": \"refs/heads/master@{#841650}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"2e89a70e3561793871480d971f4e958d04faf37a\", \"got_v8_revision\": \"2033aa929f5caa02d9ebe7cf5153491b1a3006b7\", \"got_v8_revision_cp\": \"refs/heads/8.9.224@{#1}\", \"got_webrtc_revision\": \"942976eacacf85b2a5baafdf276dafc6ff1aa47b\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32925}\", \"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\": \"9cb18c14c551a56f66c9ecf61cebd978d0d9073a\", \"swarm_hashes_refs/heads/master(at){#841650}_without_patch\": {\"absl_hardening_tests\": \"0510e4c96fefec0c5c2781ceed5b23dc675fdbf6\", \"accessibility_unittests\": \"a2beda36f1a907489fd74bed21d29537ce30665b\", \"angle_unittests\": \"22839916e9e5a454c3c7a4d90b23e0795dec5d5f\", \"app_shell_unittests\": \"b9a6df7eeaca469d61bb7b95547047b1f67662cc\", \"aura_unittests\": \"e22005d6086ac5f595fdeec6ef0a2180c526c1a7\", \"base_unittests\": \"5b7863325c90e08530030eff6a1159983a1fee2e\", \"base_util_unittests\": \"a3d072c693e9b42171670b1355586e6421fe9236\", \"blink_common_unittests\": \"984d6c8dd8061349ec92b04553886617481aea10\", \"blink_fuzzer_unittests\": \"46e46efec44d7810745e591366f174c7b0acff4d\", \"blink_heap_unittests\": \"156db994ce9e447d778e7f353bdc7d32fc066d51\", \"blink_platform_unittests\": \"74484b6e64ecfc5b89c584b44cbea6a58732d86b\", \"blink_unittests\": \"61d0368cb66c0787ac962433001d5ab28b2f5587\", \"boringssl_crypto_tests\": \"e5b622265167611dae2e87cb5924b7aa6b6ae950\", \"boringssl_ssl_tests\": \"6e4e15881bf68f6e5bfa858eca11709cae04cdfb\", \"browser_switcher_bho_unittests\": \"dabd8f67a335c4f895b87229c021488a0d893b47\", \"browser_tests\": \"d236d52ca1bbf52908a4f982312036d3c56217a2\", \"capture_unittests\": \"663add1732d1f8ade9750850121716d3115ebedf\", \"cast_unittests\": \"06c9e6fc9b96cf71ec7d7f9899c6f62032fcacae\", \"cc_unittests\": \"d853e8f29da566131f3f576f2ece0d1304d29449\", \"chrome_app_unittests\": \"daaf3290eec1f99cc835d5416c08c77ddd302de8\", \"chrome_cleaner_unittests\": \"58f2ddc2ba6cfa2b2533076e669b49ec1b336f98\", \"chrome_elf_unittests\": \"aa66d703c083c599a74e0287d98c337350638ed7\", \"chromedriver_unittests\": \"845481ec5f1a2bd0dbfc78ad7c19db5d366285c6\", \"components_browsertests\": \"10be83ed331f0ab3035c27ffe9b8d3d6bc90ae0d\", \"components_unittests\": \"c667b7e4bb941336b6881a8aa8889f86a5f37667\", \"compositor_unittests\": \"2fd4cb127ffccea41881b190da1a1280d289bbc0\", \"content_browsertests\": \"af04df0641f708b5d482a66361d37a93703e2e45\", \"content_unittests\": \"c570014f2fd42a69b13520f064407bd4f08abd7f\", \"courgette_unittests\": \"55d7140debd12148e121fb892c5ed759ab0e8bb6\", \"crashpad_tests\": \"c8f1043a2855160e8abf1e74c7cbdf6ce12b3b4b\", \"cronet_tests\": \"a214ab65af6ccb34f94f0720ddbd828c34df8df2\", \"cronet_unittests\": \"5e68c48802e2ec4224706195d2333d201c067f6e\", \"crypto_unittests\": \"02dbb1139cfc92fd8380200985b7c34ee5c8d3df\", \"delayloads_unittests\": \"e65216dc151ae348209329937cb3e6e4ab1eb787\", \"device_unittests\": \"555e2ca305136a49ccd34674539f05525e4bb853\", \"display_unittests\": \"8114d9d7502f8d1c889a4287c6ecf3f2e0b7a7fb\", \"elevation_service_unittests\": \"5b35845b5ccce9482d64407776f0dd17e2a4ff66\", \"events_unittests\": \"05d57f1a67f03b29bb1509abe30cfdaf5ed2e446\", \"extensions_browsertests\": \"bbe1a81582c399b0254831cc1276fedc293db1e6\", \"extensions_unittests\": \"dc1f91e5114b37fd4de384391efe406f8b2643be\", \"filesystem_service_unittests\": \"083a81adc87918a8c2fa0910df69e87513732890\", \"gcm_unit_tests\": \"ae912eb15e30f5fe084438c7f92d0d07a7420691\", \"gcp_unittests\": \"9f09b6d8a3d1ef0441cd858efe676b6fdc23cd84\", \"gfx_unittests\": \"6206d46c6a23cf32232813db013aa083ae64009a\", \"gin_unittests\": \"d270267d5e8a9014b8a5dab00c0de7cfe5e6c70d\", \"google_apis_unittests\": \"e110ee61860e3be76047c913308617bacc0332b7\", \"gpu_unittests\": \"88be695045fbf3087adfb1b2947e3736c11070e2\", \"gwp_asan_unittests\": \"d789a7eaaa834c5406f83b568aeff4584bb0ff1d\", \"headless_browsertests\": \"adf87b782aaf8f94915fddf1480aaa1db60fdf75\", \"headless_unittests\": \"e705197e112f17d7775141e33051a2efef4cae84\", \"install_static_unittests\": \"cdb9c410d2224d9ce89cd9b0ced965ab018966a9\", \"installer_util_unittests\": \"23519efbc29a08afb264e70a37947ec724b27512\", \"interactive_ui_tests\": \"ba06a0842da23032d26fd18ac65d41f7e1a11579\", \"ipc_tests\": \"4780f4cab42de04b6eb2c6c9850353b6ab16f68a\", \"jingle_unittests\": \"eaae3292958b5e3eb9d4c13d685c45f6cb39af9f\", \"latency_unittests\": \"83b1043813acf3b8dc807951057e6b58ab591126\", \"libjingle_xmpp_unittests\": \"78c95b53f2af4de0a933051ad36007116b795b3e\", \"liburlpattern_unittests\": \"656146748b9f5c481e640dee9a088b501dbe2200\", \"media_blink_unittests\": \"70f0678664350bed128a431bc148df79fb25a03c\", \"media_unittests\": \"188b15645bd2209844ed64b27d316f8700bdce84\", \"message_center_unittests\": \"96e3dfc7a3f8088bb849c2476ef21e3682ea115b\", \"midi_unittests\": \"b2122b349f0068dc95d56c0273fa0b99c504e3d1\", \"mojo_core_unittests\": \"596238f262981b5483498c7cd6961a1f5b2a90cf\", \"mojo_unittests\": \"5ef6d45ec82224989067f592537af5b3727f0cfa\", \"nacl_loader_unittests\": \"0d4bf450e861d919818fcf2f657e51651249784c\", \"native_theme_unittests\": \"c74ca7799e2bdcd2495e31bc74b8de825ec03151\", \"net_unittests\": \"7680b0feda768993f8a6a632975c290a852d5a7c\", \"notification_helper_unittests\": \"cdc04e359c3d6adcf0b403c6ee2ad61daf7e2c24\", \"pdf_unittests\": \"15c9f00c695dca43ed3ba7bd7a9bf22bf305a33c\", \"perfetto_unittests\": \"f842e7deba499635869e160a48de70fdf14d934c\", \"ppapi_unittests\": \"ee86b6ada6106638e6adecb305c108f4bebdba23\", \"printing_unittests\": \"0db4d6f1184bf8a027bc5e249bf8920e72c92c60\", \"remoting_unittests\": \"ad2274ea575a51a94ae09e0d0c22dd94cd50dcf3\", \"sbox_integration_tests\": \"b594fbff4e7ead48993eae8e97c8db5164aa9df7\", \"sbox_unittests\": \"ab7e0d0d7d0471d62c3cf4f7097596864c2889ed\", \"sbox_validation_tests\": \"c7d8dc953d93b09eaf4397557c6535ec35c995c7\", \"service_manager_unittests\": \"037f5255403595ac828b6dd418f5c98b7cde724d\", \"services_unittests\": \"fcbfba8715f69f73c56f26d0da51f95d55967500\", \"setup_unittests\": \"c7997db40e1ece6a56b2d05efc781a6b14d0dd95\", \"shell_dialogs_unittests\": \"26c11223721374dbb7fb340f394244b72fdc5ec2\", \"skia_unittests\": \"5ba732796ea18973bc72a9dd341171eec9ccf8b4\", \"snapshot_unittests\": \"8c013d51d468e95fecb48e337f74def0393eef28\", \"sql_unittests\": \"d40978b615954966855f0abeb2dd78bc30119113\", \"storage_unittests\": \"6d4075f0d5bc33cdf4323359a445a52f69b55f1d\", \"sync_integration_tests\": \"3b8d141972bd426390dc7de1e1afed38c65022f2\", \"traffic_annotation_auditor_unittests\": \"9e3e2911aa43193bbd0086f56ff7f0e0da4f7017\", \"ui_base_unittests\": \"7e33ac1ce24acd34e58e274d4f44aab9ea05abca\", \"ui_touch_selection_unittests\": \"5cbc431abf3c2386ee32b363dbe52a9ee7ff1783\", \"unit_tests\": \"4d1daee84f7809677ea037a160ba56ed531f410e\", \"updater_tests\": \"96bd852da94a30b77e23a931dc49821f9474a5f8\", \"url_unittests\": \"9b9f6b18d7ffd49c0768c58139b7dd9861cb3d17\", \"views_unittests\": \"47c25bcc4d6abc410b4cafa2450e80f779fa3e55\", \"viz_unittests\": \"c1a9cb1ca1d6b58a493c169968d9f881eb723ba0\", \"vr_common_unittests\": \"d31162238b2b2ef48b51cfae5f086a8e572b0d5e\", \"vr_pixeltests\": \"ab0f06669016d1ab22d3a789eeb70d4d7733b98a\", \"weblayer_browsertests\": \"81c6407fba19fb49a0b759e6ff600ec5fc8b73f4\", \"weblayer_unittests\": \"1bed72b62196c5298346d945e46b44743969a519\", \"wm_unittests\": \"ca2ebf074afecce3bce0c8d31264fa81cd6a546f\", \"wtf_unittests\": \"839941f5028d1a1398c8a0a0a5d595794f93a3bb\", \"zlib_unittests\": \"8d068c5bcf2319aa3286bcd93b2c4dcf48d4617c\", \"zucchini_unittests\": \"3f19e91d6603895f219acf46016449438bf10de7\"}}, \"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-7-w73f\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5657-0417a3f\"], \"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": "1610141286781684",
"status": "COMPLETED",
"status_changed_ts": "1610145285714833",
"tags": [
"build_address:luci.chromium.ci/win-asan/16700",
"builder:win-asan",
"buildset:commit/git/9cb18c14c551a56f66c9ecf61cebd978d0d9073a",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9cb18c14c551a56f66c9ecf61cebd978d0d9073a",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9024068271126314384",
"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/8858638209142216544/+/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:50fcb7c1794bdb10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610145285726995",
"url": "https://ci.chromium.org/b/8858638209142216544",
"utcnow_ts": "1610145287263825"
}
}
[22:34:47.286] Invocation finished in 1h6m44.74791048s with status SUCCEEDED