[12:21:25.160] New invocation is queued and will start shortly
[12:21:26.329] Starting the invocation (attempt 1)
[12:21:26.372] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:21:26.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:21:26.372] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9025552213158464768",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"937b1ebc0e1f60755b03fb98734ef456c5a54e7b\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9025552213158464768",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/937b1ebc0e1f60755b03fb98734ef456c5a54e7b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/937b1ebc0e1f60755b03fb98734ef456c5a54e7b",
"gitiles_ref:refs/heads/master"
]
}
[12:21:26.983] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1608726086454207",
"id": "8860122151128587952",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"937b1ebc0e1f60755b03fb98734ef456c5a54e7b\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1608726086821741",
"tags": [
"build_address:luci.chromium.ci/win-asan/16407",
"builder:win-asan",
"buildset:commit/git/937b1ebc0e1f60755b03fb98734ef456c5a54e7b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/937b1ebc0e1f60755b03fb98734ef456c5a54e7b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025552213158464768",
"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/8860122151128587952/+/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": "1608726086821839",
"url": "https://ci.chromium.org/b/8860122151128587952",
"utcnow_ts": "1608726086969787"
}
}
[12:21:26.983] Task URL: https://ci.chromium.org/b/8860122151128587952
[12:21:26.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:2:0) after 1m0s
[12:21:31.994] Received PubSub notification, asking Buildbucket for the build status
[12:21:32.055] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:27.130] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:2:0)
[12:22:27.130] Timer tick, asking Buildbucket for the build status
[12:22:27.225] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:22:27.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:4:0) after 1m0s
[12:23:27.270] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:4:0)
[12:23:27.270] Timer tick, asking Buildbucket for the build status
[12:23:27.362] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:23:27.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:5:0) after 1m0s
[12:24:27.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:5:0)
[12:24:27.406] Timer tick, asking Buildbucket for the build status
[12:24:27.527] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:24:27.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:6:0) after 1m0s
[12:25:27.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:6:0)
[12:25:27.546] Timer tick, asking Buildbucket for the build status
[12:25:27.640] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:25:27.640] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:7:0) after 1m0s
[12:26:27.692] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:7:0)
[12:26:27.692] Timer tick, asking Buildbucket for the build status
[12:26:27.905] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:26:27.905] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:8:0) after 1m0s
[12:27:27.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:8:0)
[12:27:27.926] Timer tick, asking Buildbucket for the build status
[12:27:28.034] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:27:28.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:9:0) after 1m0s
[12:28:28.085] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:9:0)
[12:28:28.085] Timer tick, asking Buildbucket for the build status
[12:28:28.157] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:28:28.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:10:0) after 1m0s
[12:29:28.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:10:0)
[12:29:28.187] Timer tick, asking Buildbucket for the build status
[12:29:28.332] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:29:28.332] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:11:0) after 1m0s
[12:30:28.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:11:0)
[12:30:28.373] Timer tick, asking Buildbucket for the build status
[12:30:28.429] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:30:28.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:12:0) after 1m0s
[12:31:28.462] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:12:0)
[12:31:28.462] Timer tick, asking Buildbucket for the build status
[12:31:28.598] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:31:28.598] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:13:0) after 1m0s
[12:32:28.580] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:13:0)
[12:32:28.580] Timer tick, asking Buildbucket for the build status
[12:32:28.651] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:32:28.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:14:0) after 1m0s
[12:33:28.704] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:14:0)
[12:33:28.704] Timer tick, asking Buildbucket for the build status
[12:33:28.811] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:33:28.811] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:15:0) after 1m0s
[12:34:29.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:15:0)
[12:34:29.253] Timer tick, asking Buildbucket for the build status
[12:34:29.505] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:34:29.505] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:16:0) after 1m0s
[12:35:29.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:16:0)
[12:35:29.711] Timer tick, asking Buildbucket for the build status
[12:35:30.280] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:35:30.280] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:17:0) after 1m0s
[12:36:30.542] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:17:0)
[12:36:30.542] Timer tick, asking Buildbucket for the build status
[12:36:30.708] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:36:30.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:18:0) after 1m0s
[12:37:30.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:18:0)
[12:37:30.775] Timer tick, asking Buildbucket for the build status
[12:37:30.838] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:37:30.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:19:0) after 1m0s
[12:38:30.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:19:0)
[12:38:30.892] Timer tick, asking Buildbucket for the build status
[12:38:30.992] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:38:30.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:20:0) after 1m0s
[12:39:30.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:20:0)
[12:39:30.976] Timer tick, asking Buildbucket for the build status
[12:39:31.062] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:39:31.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:21:0) after 1m0s
[12:40:31.109] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:21:0)
[12:40:31.109] Timer tick, asking Buildbucket for the build status
[12:40:31.240] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:40:31.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:22:0) after 1m0s
[12:41:31.314] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:22:0)
[12:41:31.314] Timer tick, asking Buildbucket for the build status
[12:41:31.408] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:41:31.408] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:23:0) after 1m0s
[12:42:31.411] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:23:0)
[12:42:31.411] Timer tick, asking Buildbucket for the build status
[12:42:31.470] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:42:31.470] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:24:0) after 1m0s
[12:43:32.505] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:24:0)
[12:43:32.505] Timer tick, asking Buildbucket for the build status
[12:43:32.725] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:43:32.725] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:25:0) after 1m0s
[12:44:32.734] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:25:0)
[12:44:32.734] Timer tick, asking Buildbucket for the build status
[12:44:33.220] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:44:33.220] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:26:0) after 1m0s
[12:45:33.264] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:26:0)
[12:45:33.264] Timer tick, asking Buildbucket for the build status
[12:45:33.383] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:45:33.383] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:27:0) after 1m0s
[12:46:33.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:27:0)
[12:46:33.480] Timer tick, asking Buildbucket for the build status
[12:46:33.643] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:46:33.643] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:28:0) after 1m0s
[12:47:33.720] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:28:0)
[12:47:33.720] Timer tick, asking Buildbucket for the build status
[12:47:34.156] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:47:34.156] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:29:0) after 1m0s
[12:48:34.182] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:29:0)
[12:48:34.182] Timer tick, asking Buildbucket for the build status
[12:48:34.313] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:48:34.313] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:30:0) after 1m0s
[12:49:34.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:30:0)
[12:49:34.407] Timer tick, asking Buildbucket for the build status
[12:49:34.673] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:49:34.673] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:31:0) after 1m0s
[12:50:34.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:31:0)
[12:50:34.763] Timer tick, asking Buildbucket for the build status
[12:50:35.202] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:50:35.202] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:32:0) after 1m0s
[12:51:35.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:32:0)
[12:51:35.183] Timer tick, asking Buildbucket for the build status
[12:51:35.331] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:51:35.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:33:0) after 1m0s
[12:52:35.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:33:0)
[12:52:35.451] Timer tick, asking Buildbucket for the build status
[12:52:35.605] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:52:35.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:34:0) after 1m0s
[12:53:35.653] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:34:0)
[12:53:35.653] Timer tick, asking Buildbucket for the build status
[12:53:35.770] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:53:35.771] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:35:0) after 1m0s
[12:54:35.763] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:35:0)
[12:54:35.763] Timer tick, asking Buildbucket for the build status
[12:54:35.864] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:54:35.864] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:36:0) after 1m0s
[12:55:35.883] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:36:0)
[12:55:35.883] Timer tick, asking Buildbucket for the build status
[12:55:36.205] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:55:36.205] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:37:0) after 1m0s
[12:56:36.275] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:37:0)
[12:56:36.275] Timer tick, asking Buildbucket for the build status
[12:56:36.510] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:56:36.510] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:38:0) after 1m0s
[12:57:36.611] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:38:0)
[12:57:36.611] Timer tick, asking Buildbucket for the build status
[12:57:36.686] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:57:36.686] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:39:0) after 1m0s
[12:58:36.752] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:39:0)
[12:58:36.752] Timer tick, asking Buildbucket for the build status
[12:58:36.831] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:58:36.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:40:0) after 1m0s
[12:59:37.034] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:40:0)
[12:59:37.034] Timer tick, asking Buildbucket for the build status
[12:59:37.116] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[12:59:37.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:41:0) after 1m0s
[13:00:37.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:41:0)
[13:00:37.329] Timer tick, asking Buildbucket for the build status
[13:00:37.806] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:00:37.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:42:0) after 1m0s
[13:01:37.996] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:42:0)
[13:01:37.996] Timer tick, asking Buildbucket for the build status
[13:01:38.145] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:01:38.145] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:43:0) after 1m0s
[13:02:38.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:43:0)
[13:02:38.218] Timer tick, asking Buildbucket for the build status
[13:02:38.278] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:02:38.278] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:44:0) after 1m0s
[13:03:38.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:44:0)
[13:03:38.302] Timer tick, asking Buildbucket for the build status
[13:03:38.363] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:03:38.363] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:45:0) after 1m0s
[13:04:38.399] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:45:0)
[13:04:38.399] Timer tick, asking Buildbucket for the build status
[13:04:38.494] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:04:38.494] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:46:0) after 1m0s
[13:05:38.540] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:46:0)
[13:05:38.540] Timer tick, asking Buildbucket for the build status
[13:05:39.094] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:05:39.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:47:0) after 1m0s
[13:06:39.204] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:47:0)
[13:06:39.204] Timer tick, asking Buildbucket for the build status
[13:06:39.291] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:06:39.291] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:48:0) after 1m0s
[13:07:39.416] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:48:0)
[13:07:39.416] Timer tick, asking Buildbucket for the build status
[13:07:40.597] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:07:40.597] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:49:0) after 1m0s
[13:08:40.649] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:49:0)
[13:08:40.649] Timer tick, asking Buildbucket for the build status
[13:08:40.751] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:08:40.751] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:50:0) after 1m0s
[13:09:40.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:50:0)
[13:09:40.872] Timer tick, asking Buildbucket for the build status
[13:09:40.937] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:09:40.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:51:0) after 1m0s
[13:10:41.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:51:0)
[13:10:41.110] Timer tick, asking Buildbucket for the build status
[13:10:41.225] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:10:41.225] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:52:0) after 1m0s
[13:11:41.330] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:52:0)
[13:11:41.330] Timer tick, asking Buildbucket for the build status
[13:11:41.468] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:11:41.468] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:53:0) after 1m0s
[13:12:41.477] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:53:0)
[13:12:41.477] Timer tick, asking Buildbucket for the build status
[13:12:41.586] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:12:41.586] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:54:0) after 1m0s
[13:13:41.669] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:54:0)
[13:13:41.669] Timer tick, asking Buildbucket for the build status
[13:13:42.069] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:13:42.069] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:55:0) after 1m0s
[13:14:42.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:55:0)
[13:14:42.218] Timer tick, asking Buildbucket for the build status
[13:14:42.325] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:14:42.325] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:56:0) after 1m0s
[13:15:42.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:56:0)
[13:15:42.412] Timer tick, asking Buildbucket for the build status
[13:15:42.625] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:15:42.625] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:57:0) after 1m0s
[13:16:42.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:57:0)
[13:16:42.696] Timer tick, asking Buildbucket for the build status
[13:16:42.831] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:16:42.831] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:58:0) after 1m0s
[13:17:42.941] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:58:0)
[13:17:42.941] Timer tick, asking Buildbucket for the build status
[13:17:43.487] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:17:43.487] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:59:0) after 1m0s
[13:18:43.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:59:0)
[13:18:43.551] Timer tick, asking Buildbucket for the build status
[13:18:43.623] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:18:43.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:60:0) after 1m0s
[13:19:43.645] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:60:0)
[13:19:43.645] Timer tick, asking Buildbucket for the build status
[13:19:43.716] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:19:43.716] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:61:0) after 1m0s
[13:20:43.767] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:61:0)
[13:20:43.767] Timer tick, asking Buildbucket for the build status
[13:20:44.024] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:20:44.024] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:62:0) after 1m0s
[13:21:44.106] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:62:0)
[13:21:44.106] Timer tick, asking Buildbucket for the build status
[13:21:44.217] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:21:44.217] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:63:0) after 1m0s
[13:22:44.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:63:0)
[13:22:44.317] Timer tick, asking Buildbucket for the build status
[13:22:44.412] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:22:44.412] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:64:0) after 1m0s
[13:23:44.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:64:0)
[13:23:44.395] Timer tick, asking Buildbucket for the build status
[13:23:44.723] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:23:44.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:65:0) after 1m0s
[13:24:44.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:65:0)
[13:24:44.746] Timer tick, asking Buildbucket for the build status
[13:24:46.116] Build 8860122151128587952: status "STARTED", result "", failure_reason "", cancelation_reason ""
[13:24:46.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9025552213158464768:66:0) after 1m0s
[13:25:26.901] Received PubSub notification, asking Buildbucket for the build status
[13:25:26.964] Build 8860122151128587952: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[13:25:26.964] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1608729925514893",
"created_by": "project:chromium",
"created_ts": "1608726086454207",
"id": "8860122151128587952",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"937b1ebc0e1f60755b03fb98734ef456c5a54e7b\"}}",
"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\": \"937b1ebc0e1f60755b03fb98734ef456c5a54e7b\", \"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-6-gpyc\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16407, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"d447829c19fd6275b94676aab20e303c224fe126\", \"got_dawn_revision\": \"33bf309c1d45e4da80ba77913eab9dadfc8f5e26\", \"got_nacl_revision\": \"299d4780ec86121ff2a5368ed9435ade0e356e5e\", \"got_revision\": \"937b1ebc0e1f60755b03fb98734ef456c5a54e7b\", \"got_revision_cp\": \"refs/heads/master@{#839098}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"1cc5b3357d2ff3c81ef7ec0b07a6660796bde5cd\", \"got_v8_revision\": \"711cf5ad4a12abe0f6b50058cb2248d7fb80374e\", \"got_v8_revision_cp\": \"refs/heads/8.9.187@{#1}\", \"got_webrtc_revision\": \"c463a784c340874c75032da7602ec1fde43f75ed\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32874}\", \"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\": \"937b1ebc0e1f60755b03fb98734ef456c5a54e7b\", \"swarm_hashes_refs/heads/master(at){#839098}_without_patch\": {\"absl_hardening_tests\": \"ce1f0f9b9b25e3cdf6a07a0e0b76af9716afb48f\", \"accessibility_unittests\": \"9c25cf576cee4b5d6304917b3460cd841b85afa9\", \"angle_unittests\": \"571027a9beb72b2b4a56911580ec4a89ba604851\", \"app_shell_unittests\": \"6fe27f78b3ce7e84d7228abe363eac1b7fddc8ec\", \"aura_unittests\": \"66e0fa61c402298ff9f9d9391cf3ad6b6be4177d\", \"base_unittests\": \"ff09bb7d5f2b63139b7ee1626375a378dd7e50f0\", \"base_util_unittests\": \"5c845bf46e78057b517371484642806e1e521fd4\", \"blink_common_unittests\": \"eb21fdbc2caef49497b3bdcfccae2bd0f82f4997\", \"blink_fuzzer_unittests\": \"42454873173e77f12b47037834fb8cf349669874\", \"blink_heap_unittests\": \"d486f7e50ff533a3a779fe73ed91e09029b0a994\", \"blink_platform_unittests\": \"4bd52d856f13e9d6412bb081046cb6a6ebbea198\", \"blink_unittests\": \"e98c96be0f5509cfc43ba3b132a6351cec52cbaf\", \"boringssl_crypto_tests\": \"2504f21ea2ffdfb788e76dcef30b9541551ef265\", \"boringssl_ssl_tests\": \"cedf376879c94d77766e67635c228f52690a5ae8\", \"browser_switcher_bho_unittests\": \"a778b9e8cd53b81c550f894474346318a7bfba9d\", \"browser_tests\": \"4ed0517dc8864241e7a85a2e6d07645a0bd7aa76\", \"capture_unittests\": \"6baf52caca9e23026e2c5e5251f5d7eda81f48b9\", \"cast_unittests\": \"a2aa57a1cb67e4d9f04bc1edd162f1cbda0f14c1\", \"cc_unittests\": \"4843d66f185324f440843406c22a5d278ecbf758\", \"chrome_app_unittests\": \"f961bcbffb15b9fe6708ae69076598aceb9e07a4\", \"chrome_cleaner_unittests\": \"ef985271248808e928b9d5050c1cecafc6295542\", \"chrome_elf_unittests\": \"f7faf60bb2229d6eb8cbea9c6ae648907af80823\", \"chromedriver_unittests\": \"da02573b270dc3087d0289bafc20caa404cd20e7\", \"components_browsertests\": \"71d2c4f1d69092fe74f8ad9cd949bba265c5e369\", \"components_unittests\": \"dcf4c600121e4cf6ae9a56b5bd10745b582283b1\", \"compositor_unittests\": \"c876234675c59dac2162a099181e03e11d27468c\", \"content_browsertests\": \"3a8f8dd4b4b1be2051a3976a95ad3c53dbfe5a54\", \"content_unittests\": \"89c6dec89befb034626611244d14257b71afa6e7\", \"courgette_unittests\": \"9255ed270c746a4277c076afb8e4ecc0765a665e\", \"crashpad_tests\": \"505482293273a34ff74cf1fe369e89dbddb15977\", \"cronet_tests\": \"7a9b09a8aa0c185fa6297f2c11aa679520451d21\", \"cronet_unittests\": \"32ac780691e63b92d2c3b6e10968b1c1237f25e5\", \"crypto_unittests\": \"c48cfbbd3fe8af209aefad43c9d29265df725895\", \"delayloads_unittests\": \"77658b8476bd777628d13fa6cc2c3bcc9a597fa3\", \"device_unittests\": \"e24b10062dc061a21c0a6a6b23d9debf64f6bca9\", \"display_unittests\": \"0d5e3123755d93fd59b42b3011a243f5a5b9dbbc\", \"elevation_service_unittests\": \"c330eb7e93e142dcd784d6ce9e5c49828471dc49\", \"events_unittests\": \"d62bd5e9f38333e493d0a4c6410ef141c59c2b96\", \"extensions_browsertests\": \"1c7c19e1dfc6da81978a700febc6cf7e50de1ea1\", \"extensions_unittests\": \"436645ea3a3465a15ceda2ee0d5b401e662779c1\", \"filesystem_service_unittests\": \"d092594ef413e064bdeff37e62044f5b2a101bd6\", \"gcm_unit_tests\": \"254496cf3db352ff3bc68859a34c5637d34d3a49\", \"gcp_unittests\": \"4da9f0cea42c98725ec90b226c65744944544f7a\", \"gfx_unittests\": \"e858888990f8ec196b244320534b41269673db8e\", \"gin_unittests\": \"be87c5dc4264fc574b938f678825984e75e90b79\", \"google_apis_unittests\": \"c2fb0a97fb338ae8fb65adc2fe40b6f3ae43b28a\", \"gpu_unittests\": \"e343ad00e4d9d3067bd5bc7dd4b21b1c0c2aa8c4\", \"gwp_asan_unittests\": \"4ee736f8e9eb3049d0288af289c5b9b8a75d030f\", \"headless_browsertests\": \"e84a93bcb9133703df9002c27781d9d5535139f5\", \"headless_unittests\": \"b8fb53fed795726a743201823c819f4a4b361977\", \"install_static_unittests\": \"280548038f19c95a74f1ed98de8a493fa3a63f01\", \"installer_util_unittests\": \"557fa2352ca2e123460a7a3f8989aa38ee96cc2e\", \"interactive_ui_tests\": \"f9761e0a2df4c7ce112d0328d95c3dadecfa3abe\", \"ipc_tests\": \"4a04e979b7bf4609cd781bac2f50263972d457d8\", \"jingle_unittests\": \"0f727a8e66d9bacf7747bb6206f4851c0966a9da\", \"latency_unittests\": \"8151a18aa9bfa476451f7310237886623c992928\", \"libjingle_xmpp_unittests\": \"ccd9bf5de47731a4925b3f48fc0e0084f636b7e0\", \"liburlpattern_unittests\": \"e83204cc574022d43bc66ff187ae4be4bfd1d3a0\", \"media_blink_unittests\": \"23489a8e4ab570744c4c072ce1e001db66e69bd7\", \"media_unittests\": \"741b7528ece72bdcd9163ac956afa73a192d6d75\", \"message_center_unittests\": \"2b22d186b3f507251865e0eab288f95283af966e\", \"midi_unittests\": \"806b4f6b481d690ba397eefc8299e920aabff9e4\", \"mojo_core_unittests\": \"19501930761909f9a2dc66e5f038640dca795c62\", \"mojo_unittests\": \"48b2fdae4040884d57fb569ec4fda1e1415e56c0\", \"nacl_loader_unittests\": \"3377aecbc57f3dfd4398a5326074e47c8641e075\", \"native_theme_unittests\": \"61fea70574a0ede955a363d0bd61b42736c76ea1\", \"net_unittests\": \"86dfa37e0c269a2f40a2635cd2f79ec3abec6c52\", \"notification_helper_unittests\": \"9f754df7494f6600375de3f192dd0ffa57db657c\", \"pdf_unittests\": \"b524897e199f3caa00c6c077c8bb80f9dc2219ba\", \"perfetto_unittests\": \"67327832b9d178166a745f7c4905751ae5d4144e\", \"ppapi_unittests\": \"528b450ea675e89670cef3ceae84ec4856db5712\", \"printing_unittests\": \"635a60d3faf6915d3d1adc736c9ed0663b584f7d\", \"remoting_unittests\": \"9cc0ee6c35e4d8b1efd17b135c2c0fc53eed9580\", \"sbox_integration_tests\": \"be56f3e66968a0273b92354c28e94f781ea5c76f\", \"sbox_unittests\": \"ebbdcfed84b280a25f8a8f05ce6b0571b4498c4e\", \"sbox_validation_tests\": \"5b1f6785a0e202c160a932a0d96b671710dc02e9\", \"service_manager_unittests\": \"c651b5ecd123f81227d5def5b0c5fd6cfcd9adf5\", \"services_unittests\": \"266f81b4fbfd670fcb7c2efeeacf046458f20246\", \"setup_unittests\": \"99c426eedca8600fc10b14eaf2d308616d6c89ef\", \"shell_dialogs_unittests\": \"72cf6c1e86fb36fc141b52c0c1e41150838d8011\", \"skia_unittests\": \"18ead11d3706944cc2326e691efcf502a97a4dd1\", \"snapshot_unittests\": \"5fdba06de5e313b5c25e8ae2fd52bc0c60f1bab2\", \"sql_unittests\": \"b45de565cabf3f41cf03e4f4dcf579a0121ba3a4\", \"storage_unittests\": \"a91a312b9a5dad147f1b49d36efd726ad2d83601\", \"sync_integration_tests\": \"e0c9529248df3688cab923f73146f9bf3efd2abf\", \"traffic_annotation_auditor_unittests\": \"e02b203d0be6c4c522aee20986a6984fe4ac0337\", \"ui_base_unittests\": \"d443564944c0df95efa46cb609a23eca30d61146\", \"ui_touch_selection_unittests\": \"fad905adc771ae651bed83d07cdd7acff764d62e\", \"unit_tests\": \"71d65dad8b25a46f5950626e3887361d193731ba\", \"updater_tests\": \"e40eea8c6cdcf8ce0d6fb7de55172bbaeb43e590\", \"url_unittests\": \"84cdd9e2933f621b7eb029d145ce4c3acd541a47\", \"views_unittests\": \"4b8644dd63c75f01f24361f588c1178d4d61ceea\", \"viz_unittests\": \"9451d6a714264f6a51f427d9077726ba310561a5\", \"vr_common_unittests\": \"21052d944d772eae5382edbed9c162839d4311e0\", \"vr_pixeltests\": \"c3c4328901d49613b0f3af137eca729acd4452c1\", \"weblayer_browsertests\": \"0c17e2e2b01cf0435d80e1d3786fc784b7094292\", \"weblayer_unittests\": \"ffd6e82a7008cbb57d4728b6fe582524c6d18411\", \"wm_unittests\": \"524f1039dc57a8ee38717c5eec4dcbe51c9f101d\", \"wtf_unittests\": \"9fcfc07bf261dbfd4c00be248dd40f6264a10ebe\", \"zlib_unittests\": \"e628db89766469d0cdb585c401b421df55196a2b\", \"zucchini_unittests\": \"cdb5b6a1297b26bd6c43ad609c532815f0456100\"}}, \"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-6-gpyc\"], \"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": "1608726091331868",
"status": "COMPLETED",
"status_changed_ts": "1608729925997254",
"tags": [
"build_address:luci.chromium.ci/win-asan/16407",
"builder:win-asan",
"buildset:commit/git/937b1ebc0e1f60755b03fb98734ef456c5a54e7b",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/937b1ebc0e1f60755b03fb98734ef456c5a54e7b",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9025552213158464768",
"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/8860122151128587952/+/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:50a85d8e1ba15a10",
"user_agent:luci-scheduler"
],
"updated_ts": "1608729926010496",
"url": "https://ci.chromium.org/b/8860122151128587952",
"utcnow_ts": "1608729926950924"
}
}
[13:25:26.964] Invocation finished in 1h4m1.828442931s with status SUCCEEDED