[10:46:00.386] New invocation is queued and will start shortly
[10:46:01.419] Starting the invocation (attempt 1)
[10:46:01.458] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:46:01.458] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:46:01.458] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021662546452576000",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"1e50277dc36dbdf3544538b62b32ca1efe13c45f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021662546452576000",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/1e50277dc36dbdf3544538b62b32ca1efe13c45f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e50277dc36dbdf3544538b62b32ca1efe13c45f",
"gitiles_ref:refs/heads/master"
]
}
[10:46:02.361] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612435561589764",
"id": "8856232484529376880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1e50277dc36dbdf3544538b62b32ca1efe13c45f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612435562125670",
"tags": [
"build_address:luci.chromium.ci/win-asan/17164",
"builder:win-asan",
"buildset:commit/git/1e50277dc36dbdf3544538b62b32ca1efe13c45f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e50277dc36dbdf3544538b62b32ca1efe13c45f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021662546452576000",
"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/8856232484529376880/+/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": "1612435562126066",
"url": "https://ci.chromium.org/b/8856232484529376880",
"utcnow_ts": "1612435562349392"
}
}
[10:46:02.361] Task URL: https://ci.chromium.org/b/8856232484529376880
[10:46:02.361] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:2:0) after 1m0s
[10:46:04.766] Received PubSub notification, asking Buildbucket for the build status
[10:46:04.871] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:02.434] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:2:0)
[10:47:02.434] Timer tick, asking Buildbucket for the build status
[10:47:02.594] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:02.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:4:0) after 1m0s
[10:48:03.916] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:4:0)
[10:48:03.916] Timer tick, asking Buildbucket for the build status
[10:48:04.093] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:04.093] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:5:0) after 1m0s
[10:49:04.581] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:5:0)
[10:49:04.581] Timer tick, asking Buildbucket for the build status
[10:49:04.684] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:04.684] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:6:0) after 1m0s
[10:50:04.714] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:6:0)
[10:50:04.714] Timer tick, asking Buildbucket for the build status
[10:50:05.057] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:05.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:7:0) after 1m0s
[10:51:05.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:7:0)
[10:51:05.288] Timer tick, asking Buildbucket for the build status
[10:51:05.660] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:05.660] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:8:0) after 1m0s
[10:52:05.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:8:0)
[10:52:05.809] Timer tick, asking Buildbucket for the build status
[10:52:05.873] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:05.873] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:9:0) after 1m0s
[10:53:05.898] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:9:0)
[10:53:05.898] Timer tick, asking Buildbucket for the build status
[10:53:05.972] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:05.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:10:0) after 1m0s
[10:54:05.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:10:0)
[10:54:05.965] Timer tick, asking Buildbucket for the build status
[10:54:06.171] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:06.171] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:11:0) after 1m0s
[10:55:06.238] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:11:0)
[10:55:06.238] Timer tick, asking Buildbucket for the build status
[10:55:06.299] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:06.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:12:0) after 1m0s
[10:56:06.401] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:12:0)
[10:56:06.401] Timer tick, asking Buildbucket for the build status
[10:56:07.970] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:07.970] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:13:0) after 1m0s
[10:57:08.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:13:0)
[10:57:08.177] Timer tick, asking Buildbucket for the build status
[10:57:08.495] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:08.495] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:14:0) after 1m0s
[10:58:08.654] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:14:0)
[10:58:08.654] Timer tick, asking Buildbucket for the build status
[10:58:08.793] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:08.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:15:0) after 1m0s
[10:59:09.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:15:0)
[10:59:09.012] Timer tick, asking Buildbucket for the build status
[10:59:09.772] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:09.772] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:16:0) after 1m0s
[11:00:09.837] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:16:0)
[11:00:09.837] Timer tick, asking Buildbucket for the build status
[11:00:09.977] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:09.977] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:17:0) after 1m0s
[11:01:10.353] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:17:0)
[11:01:10.353] Timer tick, asking Buildbucket for the build status
[11:01:10.481] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:10.481] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:18:0) after 1m0s
[11:02:10.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:18:0)
[11:02:10.545] Timer tick, asking Buildbucket for the build status
[11:02:10.636] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:10.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:19:0) after 1m0s
[11:03:10.742] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:19:0)
[11:03:10.742] Timer tick, asking Buildbucket for the build status
[11:03:10.856] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:10.856] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:20:0) after 1m0s
[11:04:11.043] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:20:0)
[11:04:11.043] Timer tick, asking Buildbucket for the build status
[11:04:12.670] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:12.670] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:21:0) after 1m0s
[11:05:12.666] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:21:0)
[11:05:12.666] Timer tick, asking Buildbucket for the build status
[11:05:12.785] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:12.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:22:0) after 1m0s
[11:06:12.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:22:0)
[11:06:12.847] Timer tick, asking Buildbucket for the build status
[11:06:12.960] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:12.960] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:23:0) after 1m0s
[11:07:13.012] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:23:0)
[11:07:13.012] Timer tick, asking Buildbucket for the build status
[11:07:13.140] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:13.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:24:0) after 1m0s
[11:08:13.934] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:24:0)
[11:08:13.934] Timer tick, asking Buildbucket for the build status
[11:08:14.144] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:14.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:25:0) after 1m0s
[11:09:14.253] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:25:0)
[11:09:14.253] Timer tick, asking Buildbucket for the build status
[11:09:14.912] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:14.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:26:0) after 1m0s
[11:10:14.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:26:0)
[11:10:14.967] Timer tick, asking Buildbucket for the build status
[11:10:15.127] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:15.127] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:27:0) after 1m0s
[11:11:15.147] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:27:0)
[11:11:15.147] Timer tick, asking Buildbucket for the build status
[11:11:15.331] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:15.331] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:28:0) after 1m0s
[11:12:15.377] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:28:0)
[11:12:15.377] Timer tick, asking Buildbucket for the build status
[11:12:15.694] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:15.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:29:0) after 1m0s
[11:13:15.761] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:29:0)
[11:13:15.761] Timer tick, asking Buildbucket for the build status
[11:13:15.883] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:15.883] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:30:0) after 1m0s
[11:14:15.967] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:30:0)
[11:14:15.967] Timer tick, asking Buildbucket for the build status
[11:14:16.693] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:16.693] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:31:0) after 1m0s
[11:15:16.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:31:0)
[11:15:16.782] Timer tick, asking Buildbucket for the build status
[11:15:17.624] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:17.624] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:32:0) after 1m0s
[11:16:17.781] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:32:0)
[11:16:17.782] Timer tick, asking Buildbucket for the build status
[11:16:18.257] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:18.257] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:33:0) after 1m0s
[11:17:18.373] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:33:0)
[11:17:18.373] Timer tick, asking Buildbucket for the build status
[11:17:18.554] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:18.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:34:0) after 1m0s
[11:18:18.582] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:34:0)
[11:18:18.582] Timer tick, asking Buildbucket for the build status
[11:18:18.671] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:18.671] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:35:0) after 1m0s
[11:19:18.805] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:35:0)
[11:19:18.805] Timer tick, asking Buildbucket for the build status
[11:19:19.149] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:19.149] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:36:0) after 1m0s
[11:20:19.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:36:0)
[11:20:19.276] Timer tick, asking Buildbucket for the build status
[11:20:19.461] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:19.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:37:0) after 1m0s
[11:21:19.481] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:37:0)
[11:21:19.481] Timer tick, asking Buildbucket for the build status
[11:21:19.620] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:19.620] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:38:0) after 1m0s
[11:22:19.746] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:38:0)
[11:22:19.746] Timer tick, asking Buildbucket for the build status
[11:22:19.824] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:19.824] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:39:0) after 1m0s
[11:23:19.899] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:39:0)
[11:23:19.899] Timer tick, asking Buildbucket for the build status
[11:23:19.984] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:19.984] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:40:0) after 1m0s
[11:24:20.009] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:40:0)
[11:24:20.009] Timer tick, asking Buildbucket for the build status
[11:24:20.610] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:20.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:41:0) after 1m0s
[11:25:20.633] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:41:0)
[11:25:20.633] Timer tick, asking Buildbucket for the build status
[11:25:20.778] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:20.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:42:0) after 1m0s
[11:26:20.807] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:42:0)
[11:26:20.807] Timer tick, asking Buildbucket for the build status
[11:26:21.262] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:21.262] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:43:0) after 1m0s
[11:27:21.302] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:43:0)
[11:27:21.302] Timer tick, asking Buildbucket for the build status
[11:27:21.374] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:21.374] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:44:0) after 1m0s
[11:28:21.439] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:44:0)
[11:28:21.439] Timer tick, asking Buildbucket for the build status
[11:28:21.614] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:28:21.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:45:0) after 1m0s
[11:29:21.711] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:45:0)
[11:29:21.711] Timer tick, asking Buildbucket for the build status
[11:29:21.940] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:29:21.940] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:46:0) after 1m0s
[11:30:22.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:46:0)
[11:30:22.066] Timer tick, asking Buildbucket for the build status
[11:30:22.269] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:30:22.269] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:47:0) after 1m0s
[11:31:22.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:47:0)
[11:31:22.317] Timer tick, asking Buildbucket for the build status
[11:31:22.453] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:31:22.453] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:48:0) after 1m0s
[11:32:22.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:48:0)
[11:32:22.556] Timer tick, asking Buildbucket for the build status
[11:32:22.630] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:32:22.630] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:49:0) after 1m0s
[11:33:22.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:49:0)
[11:33:22.724] Timer tick, asking Buildbucket for the build status
[11:33:23.027] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:33:23.027] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:50:0) after 1m0s
[11:34:23.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:50:0)
[11:34:23.138] Timer tick, asking Buildbucket for the build status
[11:34:23.310] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:34:23.310] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:51:0) after 1m0s
[11:35:23.396] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:51:0)
[11:35:23.396] Timer tick, asking Buildbucket for the build status
[11:35:23.473] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:35:23.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:52:0) after 1m0s
[11:36:23.575] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:52:0)
[11:36:23.575] Timer tick, asking Buildbucket for the build status
[11:36:23.713] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:36:23.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:53:0) after 1m0s
[11:37:23.762] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:53:0)
[11:37:23.762] Timer tick, asking Buildbucket for the build status
[11:37:23.909] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:37:23.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:54:0) after 1m0s
[11:38:24.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:54:0)
[11:38:24.048] Timer tick, asking Buildbucket for the build status
[11:38:24.141] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:38:24.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:55:0) after 1m0s
[11:39:24.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:55:0)
[11:39:24.305] Timer tick, asking Buildbucket for the build status
[11:39:24.414] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:39:24.414] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:56:0) after 1m0s
[11:40:24.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:56:0)
[11:40:24.451] Timer tick, asking Buildbucket for the build status
[11:40:24.929] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:40:24.929] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:57:0) after 1m0s
[11:41:24.985] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:57:0)
[11:41:24.985] Timer tick, asking Buildbucket for the build status
[11:41:25.108] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:41:25.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:58:0) after 1m0s
[11:42:25.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:58:0)
[11:42:25.355] Timer tick, asking Buildbucket for the build status
[11:42:25.744] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:42:25.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:59:0) after 1m0s
[11:43:25.859] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:59:0)
[11:43:25.859] Timer tick, asking Buildbucket for the build status
[11:43:25.987] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:43:25.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:60:0) after 1m0s
[11:44:26.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:60:0)
[11:44:26.077] Timer tick, asking Buildbucket for the build status
[11:44:26.141] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:44:26.141] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:61:0) after 1m0s
[11:45:26.203] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:61:0)
[11:45:26.203] Timer tick, asking Buildbucket for the build status
[11:45:26.535] Build 8856232484529376880: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:45:26.535] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021662546452576000:62:0) after 1m0s
[11:46:03.578] Received PubSub notification, asking Buildbucket for the build status
[11:46:03.649] Build 8856232484529376880: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:46:03.649] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612439160301509",
"created_by": "project:chromium",
"created_ts": "1612435561589764",
"id": "8856232484529376880",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"1e50277dc36dbdf3544538b62b32ca1efe13c45f\"}}",
"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\": \"1e50277dc36dbdf3544538b62b32ca1efe13c45f\", \"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-2-30ob\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17164, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"ad985fcdd806a26343b9b5f8408e551b515493e5\", \"got_dawn_revision\": \"d5a0728b67c8328edaed3d9cfee7ee44fcb893e8\", \"got_nacl_revision\": \"952e81813a66902714b68f34b51073c5d1ba6c94\", \"got_revision\": \"1e50277dc36dbdf3544538b62b32ca1efe13c45f\", \"got_revision_cp\": \"refs/heads/master@{#850524}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"14dcbed947ce755af1fcbfb3ba8e643b4e769860\", \"got_v8_revision\": \"b8307ba2ab92cba515b38d20aa32b36d4bade80c\", \"got_v8_revision_cp\": \"refs/heads/9.0.127@{#1}\", \"got_webrtc_revision\": \"1d71fd9c61db8f1dcf30b5d0625e514b91200926\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33155}\", \"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\": \"1e50277dc36dbdf3544538b62b32ca1efe13c45f\", \"swarm_hashes_refs/heads/master(at){#850524}_without_patch\": {\"absl_hardening_tests\": \"95e5bf2564a4906b7039e84e9415d1b6f46eebf6\", \"accessibility_unittests\": \"0b24243323ce40af0c4d9a387ba603a89d54c5ce\", \"angle_unittests\": \"56066148d2654757f9685227f3f7969d65be21c5\", \"app_shell_unittests\": \"3843cd287ee15ab6943d33ee251f3e998830b704\", \"aura_unittests\": \"54c3a49cd31857f1272ba77b08d49b9ddd70015c\", \"base_unittests\": \"a06b26246045d7e653b3323d5f55fc6a500198b5\", \"base_util_unittests\": \"6b347d3ca8afc1852cf53a7b205163557a14627b\", \"blink_common_unittests\": \"b6889f324ddcc2dbc9e969533b76bb3b17183610\", \"blink_fuzzer_unittests\": \"3c39fad50d12729369080806aae03a0f52235161\", \"blink_heap_unittests\": \"4b86886579d34e095f246f1cef0239f913a92998\", \"blink_platform_unittests\": \"5e99fbea609ad9cb497a16894ebfb74be35e75f6\", \"blink_unittests\": \"0360139f02541a1ed8701fc8046c14a6648f776c\", \"boringssl_crypto_tests\": \"9ea933882d8af52bf61bf9844e306663f5634c68\", \"boringssl_ssl_tests\": \"5f630df9ff7a0bb5e20d7b2046125b9babcbd5b8\", \"browser_switcher_bho_unittests\": \"83971e477ae90a31d681c3c82a337a93d79da935\", \"browser_tests\": \"f16307050b0d7e20d004c0b33ea07b8767bd5576\", \"capture_unittests\": \"70ec780ebade803289747a0257d832fae96fa237\", \"cast_unittests\": \"fcc72a6f9f9d27aa8ac5a0685fc3974a404dd4dd\", \"cc_unittests\": \"89a4542da110b704b028a028e3aded0cd4b4c2f8\", \"chrome_app_unittests\": \"27387b7f820f2f2682a90c4bbba48d2c6851764d\", \"chrome_cleaner_unittests\": \"587836c9e97267149685e04a0aa1fbb3cd416d9d\", \"chrome_elf_unittests\": \"f6db8a51bb91b0a9ebe1a2ae0ac5d19bad47ad58\", \"chromedriver_unittests\": \"16d2d79dee48e333acae3b5b070b2feafeddc1d9\", \"components_browsertests\": \"d91a58f6c2be1b2772b7a04ef64c9d4acf153c91\", \"components_unittests\": \"5369e8d60fbdebe63cef9d24b5fd3c82454b2d2b\", \"compositor_unittests\": \"e33d826f339fb8a6065fca6eacd293f9a28cb020\", \"content_browsertests\": \"60a6ef787d6a41b5d90d709444283e55f10d8fd8\", \"content_unittests\": \"d01665cef2a7461e3db146a3bd45bd71e7ff5545\", \"courgette_unittests\": \"6cf384d45de0b6fa81b8df5dcf5f447ac30b942d\", \"crashpad_tests\": \"3fa2c31d01984c3034f7e220df6f5b851ee63dfd\", \"cronet_tests\": \"d0daeda266334e50b38e331f87bea10d5a3f5156\", \"cronet_unittests\": \"f96cb3f96cb33225eb5fbc1776f4922ce63e0bc1\", \"crypto_unittests\": \"0b833cc1978decfd5b076f35ff24d8ce7e374135\", \"delayloads_unittests\": \"92a25b6892cb0463547de1945fd93572b0bb6b79\", \"device_unittests\": \"dcdad00b39271c6e653133b47e4c89eddaeb1dd6\", \"display_unittests\": \"ef8bfac730ed44d875a503c554bb1dd6f3c516d8\", \"elevation_service_unittests\": \"3f36b67dee73a03c54072529b36cec68abcf8f24\", \"events_unittests\": \"5888254d3500c61c8b564fe4b47f3d2903d4c330\", \"extensions_browsertests\": \"b0e368eb0020623eb20b52931f9609946ae2545b\", \"extensions_unittests\": \"b43477796690df815dbcd0970682659215e17278\", \"filesystem_service_unittests\": \"72582afc248505eaad3c7a4008fd393c02ce5fc3\", \"gcm_unit_tests\": \"f8836a3bb2d828ef24426d55a7761e625603598d\", \"gcp_unittests\": \"365514fed6014a9e9b2a2b72c6b0e363a6f54d04\", \"gfx_unittests\": \"968ddf6c206dc69488692f51a516780f0259cb84\", \"gin_unittests\": \"639b45e68b393dbdce529e0b1f757a12a1b03a21\", \"google_apis_unittests\": \"6fccd4e408af16d15c492569cfdba8d11971d43a\", \"gpu_unittests\": \"af1b3079851d378c8ac298ae2e6376669d695cf0\", \"gwp_asan_unittests\": \"4f1d274945b15dffc53aac3dfae84c124ae6de17\", \"headless_browsertests\": \"743a6187bd5e26cea14b56f9bb7d814b1ff65f29\", \"headless_unittests\": \"8f2d33347d19f56c7a979ea3038be685a97d44cc\", \"install_static_unittests\": \"564c03f84d328751e25385611f2870accebc9fac\", \"installer_util_unittests\": \"d4d3694743c582649a6c7ea1235f6d85c95834f6\", \"interactive_ui_tests\": \"6fbf5cdf5eae7df3f366be9e2d1d5b19cd3b0eff\", \"ipc_tests\": \"912a0c93d7d3e13f9306c931cb9ff00e755f36da\", \"jingle_unittests\": \"75b7f8b86d4524d68f42aeeeaf71a684c59f5baf\", \"latency_unittests\": \"f664c375ef5bc72a2a78af77b75daba0093e5eaf\", \"libjingle_xmpp_unittests\": \"aa34bdb105a7e5be76bbc4d5aa082a2ea6d59199\", \"liburlpattern_unittests\": \"29a6b45f18aabac4d78355ce39cf22128291e1c1\", \"media_blink_unittests\": \"f728847dc1461ca3ef718db907c1931f2b34dd08\", \"media_unittests\": \"1204a2150d66ffbb3645e655d65abef44aa5ca31\", \"message_center_unittests\": \"16952f560e97eb9cf4f07e25da37445ea516535e\", \"midi_unittests\": \"7b88be8733e03670fbd0213a5fb0a85bab87aaba\", \"mojo_core_unittests\": \"5f663ba9b92202272fe4a8b5e67afac5eccbeeca\", \"mojo_unittests\": \"9b1100e04a8f3c6f064a75696b5f4d4aee9b58c0\", \"nacl_loader_unittests\": \"6fbb90b2d8d2d14b28b636bfcc99735d4f4610df\", \"native_theme_unittests\": \"698e483ac84e9795d7d5968b949891a43172179e\", \"net_unittests\": \"52494d11090372e6c8a22cfe995f6a02dd908757\", \"notification_helper_unittests\": \"3ce1154f6e1454441be47cb003c68d127be354d1\", \"pdf_unittests\": \"ec11215c40af977b12a06ea04af9557c312ff451\", \"perfetto_unittests\": \"ed05e867855a40d377c06a465c1633b2e90ae388\", \"ppapi_unittests\": \"26ffc15779f5f03ec5900d41fe609326db15aeed\", \"printing_unittests\": \"fe9bc2ed47e5a76a816732675d873da2e907b2d7\", \"remoting_unittests\": \"08180cdf0539aca9c147d71ba61c508277d48745\", \"sbox_integration_tests\": \"c89f841914416a605ccd239089575f6adcbd0db0\", \"sbox_unittests\": \"054d8c2e7fcc77b8981dd583251052afa1653266\", \"sbox_validation_tests\": \"a2d44d714251a93736a90da398f9470f7d9ef530\", \"service_manager_unittests\": \"ca381adf0844ef75640de11a8578651692c2beb5\", \"services_unittests\": \"3268cb158c0e1e175b81562ffd3ae5ad1700f06c\", \"setup_unittests\": \"7fada3e1541a1bcf02086f06456b65741a0d555c\", \"shell_dialogs_unittests\": \"e1d055a6f536bf8d162b5029a151f9b9a85375c1\", \"skia_unittests\": \"b4d665c4cdcebb462a0878fc9cc2fd02c222510c\", \"snapshot_unittests\": \"ac66c7a17cc755b6de04f991e9347f92030753b8\", \"sql_unittests\": \"7a6ba238c1ef7aabf7234e417013082eceff166d\", \"storage_unittests\": \"0c8886d900d0547c5da2b6334f1a85303b107c0b\", \"sync_integration_tests\": \"0fd56b209394871b8a69f49049b250b9ac159170\", \"traffic_annotation_auditor_unittests\": \"29f29f481265c5413b318494ba5f8eff1c6dc645\", \"ui_base_unittests\": \"facb2977231b5986bd219e8f4cc60ce652f8e107\", \"ui_touch_selection_unittests\": \"7e4c47ff670ea30a29e6be5b77b1414dff62550c\", \"unit_tests\": \"58d5a1db0a3d6a523614503b82c46df61861d4d2\", \"updater_tests\": \"1fabcb8bc82e0b8a747ae225069a268e6f923db1\", \"url_unittests\": \"237f2e1cf37d190de3f5596038b580eef5052558\", \"views_unittests\": \"8d8eceb5e4d4a3f6744d5767cfef273a24dbf26c\", \"viz_unittests\": \"af58d5e8b14f78de524ed9931df5c235cd379e08\", \"vr_common_unittests\": \"589a4e718f223207ec40b1cf21b5e26cfea2dec8\", \"vr_pixeltests\": \"9ae47fe40bce68c3841ab428ff066a0acb4f8401\", \"weblayer_browsertests\": \"62b17fa3304c45e78521d619d284b875f4d34785\", \"weblayer_unittests\": \"ee3da4899de373170df7bd0a97b4fd4009f9a88d\", \"wm_unittests\": \"2e80167497636896642093567939ded0ac9c65ba\", \"wtf_unittests\": \"d9c59d4b1e088cb89cb65792e59246c93d1fa508\", \"zlib_unittests\": \"230c98021daa0f5155374bd3fb0299e2eccc429a\", \"zucchini_unittests\": \"d3e4dd18bd84e7b9602c89e6b99c7619ba26f3f8\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_02613bacee8cd1c41158238da4ce7615021ecb28a4b52e6029f89cb601af0790_v2\", \"builder_a4ee364c4b5996d1649f16c77d7186222b75eb5b75762fbbe3baeec97aac12d9_v2\", \"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-2-30ob\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5676-c76598c\"], \"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": "1612435564094910",
"status": "COMPLETED",
"status_changed_ts": "1612439161083995",
"tags": [
"build_address:luci.chromium.ci/win-asan/17164",
"builder:win-asan",
"buildset:commit/git/1e50277dc36dbdf3544538b62b32ca1efe13c45f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1e50277dc36dbdf3544538b62b32ca1efe13c45f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021662546452576000",
"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/8856232484529376880/+/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:518577a7acf6c010",
"user_agent:luci-scheduler"
],
"updated_ts": "1612439161102366",
"url": "https://ci.chromium.org/b/8856232484529376880",
"utcnow_ts": "1612439163640023"
}
}
[11:46:03.649] Invocation finished in 1h0m3.278473739s with status SUCCEEDED