[04:56:52.308] New invocation is queued and will start shortly
[04:56:53.547] Starting the invocation (attempt 1)
[04:56:53.596] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:56:53.596] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:56:53.596] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9027301524023838208",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1402722e75f922ba4bb08d116c080e0ae1e75168\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9027301524023838208",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1402722e75f922ba4bb08d116c080e0ae1e75168",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1402722e75f922ba4bb08d116c080e0ae1e75168",
"gitiles_ref:refs/heads/master"
]
}
[04:56:54.357] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1607057813695697",
"id": "8861871461905063104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1402722e75f922ba4bb08d116c080e0ae1e75168\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1607057814204152",
"tags": [
"build_address:luci.chromium.ci/win-asan/16017",
"builder:win-asan",
"buildset:commit/git/1402722e75f922ba4bb08d116c080e0ae1e75168",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1402722e75f922ba4bb08d116c080e0ae1e75168",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027301524023838208",
"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/8861871461905063104/+/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": "1607057814204309",
"url": "https://ci.chromium.org/b/8861871461905063104",
"utcnow_ts": "1607057814348290"
}
}
[04:56:54.357] Task URL: https://ci.chromium.org/b/8861871461905063104
[04:56:54.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:2:0) after 1m0s
[04:56:57.073] Received PubSub notification, asking Buildbucket for the build status
[04:56:57.155] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:54.385] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:2:0)
[04:57:54.385] Timer tick, asking Buildbucket for the build status
[04:57:54.491] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:57:54.491] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:4:0) after 1m0s
[04:58:54.550] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:4:0)
[04:58:54.550] Timer tick, asking Buildbucket for the build status
[04:58:54.660] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:58:54.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:5:0) after 1m0s
[04:59:54.830] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:5:0)
[04:59:54.830] Timer tick, asking Buildbucket for the build status
[04:59:54.992] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:59:54.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:6:0) after 1m0s
[05:00:55.108] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:6:0)
[05:00:55.108] Timer tick, asking Buildbucket for the build status
[05:00:55.513] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:00:55.513] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:7:0) after 1m0s
[05:01:55.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:7:0)
[05:01:55.693] Timer tick, asking Buildbucket for the build status
[05:01:56.169] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:01:56.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:8:0) after 1m0s
[05:02:56.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:8:0)
[05:02:56.422] Timer tick, asking Buildbucket for the build status
[05:02:56.499] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:02:56.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:9:0) after 1m0s
[05:03:56.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:9:0)
[05:03:56.696] Timer tick, asking Buildbucket for the build status
[05:03:56.838] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:03:56.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:10:0) after 1m0s
[05:04:56.930] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:10:0)
[05:04:56.930] Timer tick, asking Buildbucket for the build status
[05:04:56.997] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:04:56.997] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:11:0) after 1m0s
[05:05:57.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:11:0)
[05:05:57.038] Timer tick, asking Buildbucket for the build status
[05:05:57.181] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:05:57.181] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:12:0) after 1m0s
[05:06:57.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:12:0)
[05:06:57.274] Timer tick, asking Buildbucket for the build status
[05:06:57.438] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:06:57.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:13:0) after 1m0s
[05:07:57.482] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:13:0)
[05:07:57.482] Timer tick, asking Buildbucket for the build status
[05:07:57.592] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:07:57.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:14:0) after 1m0s
[05:08:57.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:14:0)
[05:08:57.724] Timer tick, asking Buildbucket for the build status
[05:08:57.833] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:08:57.833] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:15:0) after 1m0s
[05:09:57.888] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:15:0)
[05:09:57.888] Timer tick, asking Buildbucket for the build status
[05:09:58.976] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:09:58.976] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:16:0) after 1m0s
[05:10:59.105] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:16:0)
[05:10:59.105] Timer tick, asking Buildbucket for the build status
[05:10:59.204] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:10:59.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:17:0) after 1m0s
[05:11:59.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:17:0)
[05:11:59.276] Timer tick, asking Buildbucket for the build status
[05:11:59.406] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:11:59.406] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:18:0) after 1m0s
[05:12:59.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:18:0)
[05:12:59.475] Timer tick, asking Buildbucket for the build status
[05:12:59.752] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:12:59.752] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:19:0) after 1m0s
[05:13:59.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:19:0)
[05:13:59.813] Timer tick, asking Buildbucket for the build status
[05:14:00.357] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:14:00.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:20:0) after 1m0s
[05:15:00.510] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:20:0)
[05:15:00.510] Timer tick, asking Buildbucket for the build status
[05:15:00.738] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:15:00.738] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:21:0) after 1m0s
[05:16:01.786] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:21:0)
[05:16:01.786] Timer tick, asking Buildbucket for the build status
[05:16:01.998] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:16:01.998] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:22:0) after 1m0s
[05:17:02.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:22:0)
[05:17:02.334] Timer tick, asking Buildbucket for the build status
[05:17:02.451] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:17:02.451] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:23:0) after 1m0s
[05:18:02.502] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:23:0)
[05:18:02.502] Timer tick, asking Buildbucket for the build status
[05:18:02.641] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:18:02.641] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:24:0) after 1m0s
[05:19:02.699] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:24:0)
[05:19:02.699] Timer tick, asking Buildbucket for the build status
[05:19:02.828] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:19:02.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:25:0) after 1m0s
[05:20:02.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:25:0)
[05:20:02.867] Timer tick, asking Buildbucket for the build status
[05:20:03.008] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:20:03.008] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:26:0) after 1m0s
[05:21:03.039] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:26:0)
[05:21:03.039] Timer tick, asking Buildbucket for the build status
[05:21:03.561] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:21:03.561] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:27:0) after 1m0s
[05:22:03.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:27:0)
[05:22:03.633] Timer tick, asking Buildbucket for the build status
[05:22:04.075] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:22:04.075] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:28:0) after 1m0s
[05:23:04.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:28:0)
[05:23:04.112] Timer tick, asking Buildbucket for the build status
[05:23:04.289] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:23:04.289] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:29:0) after 1m0s
[05:24:04.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:29:0)
[05:24:04.401] Timer tick, asking Buildbucket for the build status
[05:24:04.581] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:24:04.581] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:30:0) after 1m0s
[05:25:04.607] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:30:0)
[05:25:04.607] Timer tick, asking Buildbucket for the build status
[05:25:04.704] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:25:04.704] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:31:0) after 1m0s
[05:26:04.727] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:31:0)
[05:26:04.727] Timer tick, asking Buildbucket for the build status
[05:26:04.900] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:26:04.900] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:32:0) after 1m0s
[05:27:04.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:32:0)
[05:27:04.989] Timer tick, asking Buildbucket for the build status
[05:27:05.410] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:27:05.410] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:33:0) after 1m0s
[05:28:05.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:33:0)
[05:28:05.473] Timer tick, asking Buildbucket for the build status
[05:28:05.662] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:28:05.662] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:34:0) after 1m0s
[05:29:05.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:34:0)
[05:29:05.665] Timer tick, asking Buildbucket for the build status
[05:29:05.739] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:29:05.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:35:0) after 1m0s
[05:30:05.813] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:35:0)
[05:30:05.813] Timer tick, asking Buildbucket for the build status
[05:30:05.992] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:30:05.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:36:0) after 1m0s
[05:31:06.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:36:0)
[05:31:06.062] Timer tick, asking Buildbucket for the build status
[05:31:06.244] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:31:06.244] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:37:0) after 1m0s
[05:32:06.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:37:0)
[05:32:06.269] Timer tick, asking Buildbucket for the build status
[05:32:06.644] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:32:06.644] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:38:0) after 1m0s
[05:33:06.691] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:38:0)
[05:33:06.691] Timer tick, asking Buildbucket for the build status
[05:33:07.356] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:33:07.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:39:0) after 1m0s
[05:34:07.406] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:39:0)
[05:34:07.406] Timer tick, asking Buildbucket for the build status
[05:34:07.663] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:34:07.663] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:40:0) after 1m0s
[05:35:07.683] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:40:0)
[05:35:07.683] Timer tick, asking Buildbucket for the build status
[05:35:08.005] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:35:08.005] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:41:0) after 1m0s
[05:36:08.056] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:41:0)
[05:36:08.056] Timer tick, asking Buildbucket for the build status
[05:36:08.192] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:36:08.192] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:42:0) after 1m0s
[05:37:08.219] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:42:0)
[05:37:08.219] Timer tick, asking Buildbucket for the build status
[05:37:08.593] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:37:08.593] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:43:0) after 1m0s
[05:38:08.602] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:43:0)
[05:38:08.602] Timer tick, asking Buildbucket for the build status
[05:38:08.826] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:38:08.826] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:44:0) after 1m0s
[05:39:08.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:44:0)
[05:39:08.808] Timer tick, asking Buildbucket for the build status
[05:39:09.054] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:39:09.054] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:45:0) after 1m0s
[05:40:09.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:45:0)
[05:40:09.077] Timer tick, asking Buildbucket for the build status
[05:40:09.219] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:40:09.219] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:46:0) after 1m0s
[05:41:09.237] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:46:0)
[05:41:09.237] Timer tick, asking Buildbucket for the build status
[05:41:09.388] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:41:09.388] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:47:0) after 1m0s
[05:42:09.430] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:47:0)
[05:42:09.430] Timer tick, asking Buildbucket for the build status
[05:42:09.573] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:42:09.573] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:48:0) after 1m0s
[05:43:09.670] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:48:0)
[05:43:09.670] Timer tick, asking Buildbucket for the build status
[05:43:09.850] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:43:09.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:49:0) after 1m0s
[05:44:09.893] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:49:0)
[05:44:09.893] Timer tick, asking Buildbucket for the build status
[05:44:10.038] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:44:10.038] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:50:0) after 1m0s
[05:45:10.062] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:50:0)
[05:45:10.062] Timer tick, asking Buildbucket for the build status
[05:45:14.768] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:45:14.768] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:51:0) after 1m0s
[05:46:14.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:51:0)
[05:46:14.914] Timer tick, asking Buildbucket for the build status
[05:46:15.157] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:46:15.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:52:0) after 1m0s
[05:47:15.207] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:52:0)
[05:47:15.207] Timer tick, asking Buildbucket for the build status
[05:47:15.356] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:47:15.356] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:53:0) after 1m0s
[05:48:15.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:53:0)
[05:48:15.375] Timer tick, asking Buildbucket for the build status
[05:48:15.445] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:48:15.445] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:54:0) after 1m0s
[05:49:15.474] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:54:0)
[05:49:15.474] Timer tick, asking Buildbucket for the build status
[05:49:15.783] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:49:15.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:55:0) after 1m0s
[05:50:15.808] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:55:0)
[05:50:15.808] Timer tick, asking Buildbucket for the build status
[05:50:15.884] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:50:15.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:56:0) after 1m0s
[05:51:15.926] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:56:0)
[05:51:15.926] Timer tick, asking Buildbucket for the build status
[05:51:16.043] Build 8861871461905063104: status "STARTED", result "", failure_reason "", cancelation_reason ""
[05:51:16.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9027301524023838208:57:0) after 1m0s
[05:51:53.931] Received PubSub notification, asking Buildbucket for the build status
[05:51:54.194] Build 8861871461905063104: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[05:51:54.194] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1607061112481322",
"created_by": "project:chromium",
"created_ts": "1607057813695697",
"id": "8861871461905063104",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1402722e75f922ba4bb08d116c080e0ae1e75168\"}}",
"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\": \"1402722e75f922ba4bb08d116c080e0ae1e75168\", \"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-9-v8md\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16017, \"clang_revision\": \"llvmorg-12-init-12923-g6ee22ca6-1\", \"got_angle_revision\": \"1ad5791d97bea23f092b206679a56302defa378f\", \"got_dawn_revision\": \"10cb17e079e14f4f9a1bdaea9b46b343c82c4679\", \"got_nacl_revision\": \"3ac0b060c794b64531b47dfded8ff8d951a5c648\", \"got_revision\": \"1402722e75f922ba4bb08d116c080e0ae1e75168\", \"got_revision_cp\": \"refs/heads/master@{#833613}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"bae138de13ac876cbca0e30a40b89dc808e2b29d\", \"got_v8_revision\": \"8abf9fa063fe201bc8a865d8da055e024decc28e\", \"got_v8_revision_cp\": \"refs/heads/8.9.97@{#1}\", \"got_webrtc_revision\": \"31d3b217d3b5c9e98559bb0ef9612659b36e88e5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32766}\", \"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\": \"1402722e75f922ba4bb08d116c080e0ae1e75168\", \"swarm_hashes_refs/heads/master(at){#833613}_without_patch\": {\"absl_hardening_tests\": \"b7756871b76c5c8f76958c3b65646b96710a3dd1\", \"accessibility_unittests\": \"32a3fc666e72525ed93a4165fbd61fb0818422c3\", \"angle_unittests\": \"e72423752f4ffaa0acadec0a32e049e123a1de58\", \"app_shell_unittests\": \"b90ce0b74f67ff76790076c2bd3568f56973bc76\", \"aura_unittests\": \"62646ca6a45fe9bf5390f96a1dbb76469642ca6f\", \"base_unittests\": \"046c6c1e85552824d25d060f47884f84de6df88a\", \"base_util_unittests\": \"1699349db27a792e99eb8fc6ebb11a12a3bf5e29\", \"blink_common_unittests\": \"57f73e17a72e62b524f477ea6e41b8246ace7931\", \"blink_fuzzer_unittests\": \"239db250f279ed547869e9caf65b4f39a4846bb4\", \"blink_heap_unittests\": \"62d9359405760a184c9549c94db69cf7309cc089\", \"blink_platform_unittests\": \"506a17c9b78b30188e37bd9e9cee1040e592b278\", \"blink_unittests\": \"4fba56734d901187887bd61c727fd1ddd7993019\", \"boringssl_crypto_tests\": \"2a99d76fb3aa71918e26b158a081fb316dc1fc01\", \"boringssl_ssl_tests\": \"31d5e8b91d72b678d61592480e3cf8833a9a50ff\", \"browser_switcher_bho_unittests\": \"87964c28a7f623db64c5a68c90ef1bcab6e3b070\", \"browser_tests\": \"59cecb506f7acf9dbb8ab4c559de25319f04ae9f\", \"capture_unittests\": \"d5a4e484149b6d23f3b8acdd7c4339affcc17c38\", \"cast_unittests\": \"d1c34e57586a22009062c28312b623e438381c43\", \"cc_unittests\": \"9b7ca47a975e06c7c70be0cde9cc92d79c75f474\", \"chrome_app_unittests\": \"74131d30055b2ff3848bba0be0b874c73fa2281c\", \"chrome_cleaner_unittests\": \"2ba1303985d36ddb2f4f69b8acd9ab3b1cdef97e\", \"chrome_elf_unittests\": \"1006ecdac0976a4e7305f0fea1709e2b4b0a93e9\", \"chromedriver_unittests\": \"8019a25a84c29e6e6d445adb9947e8bf34b99d23\", \"components_browsertests\": \"c33799e294d725854c2cb57253e7cf868d403d3e\", \"components_unittests\": \"1a478d313c8c467f93e32a0a1087c392e72f43a3\", \"compositor_unittests\": \"c21b2d327a9ec780de22b5b74b31ca3b55174a21\", \"content_browsertests\": \"f17ee1394db42b6a767f9f716aad58648588ee29\", \"content_unittests\": \"f075f379efb6fdf8a95875a890ff63f3d2c84d3e\", \"courgette_unittests\": \"ecd1a3d3372684f2322baa183813c64a0438d2f1\", \"crashpad_tests\": \"a6d764f38b76722e3e5170d9b26abbac3f0ff477\", \"cronet_tests\": \"1772e9f056da3baf4ba409343ab07937e8078b8f\", \"cronet_unittests\": \"e1c8794ade5299bd47dadc5107294244e912efee\", \"crypto_unittests\": \"796e65fb3824b4989ad7e61b47c4fe26f4e2bdbd\", \"delayloads_unittests\": \"731a4a11432101c1cd79ac130a7ee9035b196181\", \"device_unittests\": \"8b674fa358ad2e24f5e2973cafcc01eb77979e9e\", \"display_unittests\": \"5b96ce0017bdbe8e5698971ab15a3c6885435c6a\", \"elevation_service_unittests\": \"85a1a65ca58c1ee8b9c668c331f48f29966eab19\", \"events_unittests\": \"f09eb5c5f4e0c5892f34e4a0792542d3a833eb98\", \"extensions_browsertests\": \"c2505903ad701e5cccf8785cb24a8958f5493c44\", \"extensions_unittests\": \"fcdd9c999db7207be9806b2ced538fed1557c57a\", \"filesystem_service_unittests\": \"01cc936fde237aa4c8f1fb9978708b08be2f76a8\", \"gcm_unit_tests\": \"af018a91a43bfb6f9c75b8afc465ec207633cf2e\", \"gcp_unittests\": \"5616008ce078f7bf11e5427ef69bec06500d3af1\", \"gfx_unittests\": \"fe697217b7cfa3e66e066185caa67593c4ba092f\", \"gin_unittests\": \"0fa912eebafc17ba11a85aaba77fc0cba2112d8c\", \"google_apis_unittests\": \"055dd091c6f9b89063137ec6b238574b00759f77\", \"gpu_unittests\": \"e8cbba25b5d06e6a738cc71aa906108b19cbda6e\", \"gwp_asan_unittests\": \"93cac1d852cbcaa6e4223d3961c80836796dc6d3\", \"headless_browsertests\": \"2cbd6fa30d5f193134d08e001d41025922489d87\", \"headless_unittests\": \"362a7eb09409ac63ae0c828ae3169fa56152143e\", \"install_static_unittests\": \"ffc77995ca513d00b1cb6f5e1ac959b7644e9bc4\", \"installer_util_unittests\": \"5f7326f544949ac042d50ef3a99dbeb5ed6f7b9b\", \"interactive_ui_tests\": \"221717b90aa16a2cc7a43d076334b9ec2127679a\", \"ipc_tests\": \"1db60d4f2ae6103578e51c4f08d4bcd65aee6931\", \"jingle_unittests\": \"e0e77ee86990e6c0b7c18060dc7f73d3a5754550\", \"latency_unittests\": \"e9e3a48c078ed2d99fab5a489e582a0df167e00d\", \"libjingle_xmpp_unittests\": \"a6a000c484606deca7e1812362878b7cbe6687be\", \"liburlpattern_unittests\": \"21de48be7e51314cd70b29136416a837f3610650\", \"media_blink_unittests\": \"08f67657623ce09b2c87e027470db17b268e5f26\", \"media_unittests\": \"25aaed6db9afc979f8c67cda4f9a710981d8c427\", \"message_center_unittests\": \"07fd3254b120532a19362effce9de235f1078681\", \"midi_unittests\": \"a1aaf4435a7fbeda8e4e9d1586567124a7ddd45b\", \"mojo_core_unittests\": \"ae18bc36b3563c5abe80832c2005c84c63263c4f\", \"mojo_unittests\": \"2e0bab006acffbfc043d36b06c5ae86ea1ac31a8\", \"nacl_loader_unittests\": \"24c11dcb4983ac19b0c70ff1ec4e77edcf0533da\", \"native_theme_unittests\": \"33a8920811929ce2a88feebe3384c47b258259a0\", \"net_unittests\": \"11d9323bd282d267bd17c6b3830dabd815ab7349\", \"notification_helper_unittests\": \"446ff09241a0c4f0189cc78c14d032b665219e8b\", \"pdf_unittests\": \"53c9e8f1e67a1972d7fe1ac8eceaf61a148f413c\", \"perfetto_unittests\": \"e56fdd95c28e1e1de8b96ec3f09d5e95dee8822c\", \"ppapi_unittests\": \"b4647497aa65702df2efeb1435ebe37505904eff\", \"printing_unittests\": \"fe24b8b7336ec49f75c1583f0d1c881589ec7a7d\", \"remoting_unittests\": \"2cf559284d94393da8aa0ba443d1e5bb38efcd8c\", \"sbox_integration_tests\": \"d2fa8db8b4740eec10235bc7985ec56d3179986c\", \"sbox_unittests\": \"69a82b0364eb1dcb6957ef4e40f6d71b823ae847\", \"sbox_validation_tests\": \"9231cd0b5cc442d07029ac72db9f5a466ba4febf\", \"service_manager_unittests\": \"42c06ecfacce9f48c19767e68c64b440cad0b671\", \"services_unittests\": \"2dea13dda14debbfc3b1288fdc5ae904665247a3\", \"setup_unittests\": \"86dd898450c15fa3b3672c02dbe7fe0b9e77c1e8\", \"shell_dialogs_unittests\": \"2addf8eaaa90dc7b443e1ef4753549d8e1438916\", \"skia_unittests\": \"aabd0f7eca09c43e766b99c064c561f2b169d181\", \"snapshot_unittests\": \"506ce9fe1b9f144a5f74aac25fc642e1418be140\", \"sql_unittests\": \"96e9c602bb664c98bec84967bb23e9c28806b91b\", \"storage_unittests\": \"7dd34eee57fb1ab8c2e576f7893c242599eb4cf0\", \"sync_integration_tests\": \"1fc4926194702ddd93430b34c27aa62bbbf2a56e\", \"traffic_annotation_auditor_unittests\": \"3ed4540dd979161cc88a2f390db2a3ffb1ab760d\", \"ui_base_unittests\": \"a0a2f3bffbb4be001e2f2401c59ee4c18f2b7fce\", \"ui_touch_selection_unittests\": \"64c2ae5e671f9d79420559e65ec95be571555bb9\", \"unit_tests\": \"678fe147f392ff66555633fdb0ed07e218608ed3\", \"updater_tests\": \"dd43d0111c396689ebad0a4fbd21968444c1e4b8\", \"url_unittests\": \"923e9b67d2af85706269add562adc0aaaa00aa1e\", \"views_unittests\": \"0023c22bdeb2807ef8f83bf799b90b371d4dcaeb\", \"viz_unittests\": \"c0b57d12162535b18605085e48ce73947a0f8650\", \"vr_common_unittests\": \"2ed66760f30e0fdd523b7f0885e47137321d924c\", \"vr_pixeltests\": \"efe60596a78275a276f013be942df35d95836aac\", \"weblayer_browsertests\": \"40495800989539bc3924c8cafe4b7165d885a78c\", \"weblayer_unittests\": \"18bd8c3c8f84f4e1069156077877dea635e82e2a\", \"wm_unittests\": \"d6dc06534ffcdf10c9346beae73aeaf2868da50b\", \"wtf_unittests\": \"ec38bb853f0f92edaaedd2fc67072966da61b9bb\", \"zlib_unittests\": \"3dea71dbee3fc737ec98d921566bfe3fed38e233\", \"zucchini_unittests\": \"2f7383a3755fc337882ae2fa8d2444c53c8a171b\"}}, \"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-9-v8md\"], \"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\": [\"5640-d0eb544\"], \"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": "1607057816001745",
"status": "COMPLETED",
"status_changed_ts": "1607061113017866",
"tags": [
"build_address:luci.chromium.ci/win-asan/16017",
"builder:win-asan",
"buildset:commit/git/1402722e75f922ba4bb08d116c080e0ae1e75168",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1402722e75f922ba4bb08d116c080e0ae1e75168",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9027301524023838208",
"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/8861871461905063104/+/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:5044edbb9717a710",
"user_agent:luci-scheduler"
],
"updated_ts": "1607061113023579",
"url": "https://ci.chromium.org/b/8861871461905063104",
"utcnow_ts": "1607061114122273"
}
}
[05:51:54.194] Invocation finished in 55m1.903232349s with status SUCCEEDED