[10:29:10.808] New invocation is queued and will start shortly
[10:29:11.879] Starting the invocation (attempt 1)
[10:29:11.917] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[10:29:11.917] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[10:29:11.917] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9020032859677398032",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"31e575207ffe69d0a3cf89c1ef24b822f49b5aa9\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9020032859677398032",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/31e575207ffe69d0a3cf89c1ef24b822f49b5aa9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31e575207ffe69d0a3cf89c1ef24b822f49b5aa9",
"gitiles_ref:refs/heads/master"
]
}
[10:29:12.558] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1613989751993286",
"id": "8854602797772562768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31e575207ffe69d0a3cf89c1ef24b822f49b5aa9\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1613989752424156",
"tags": [
"build_address:luci.chromium.ci/win-asan/17468",
"builder:win-asan",
"buildset:commit/git/31e575207ffe69d0a3cf89c1ef24b822f49b5aa9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31e575207ffe69d0a3cf89c1ef24b822f49b5aa9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020032859677398032",
"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/8854602797772562768/+/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": "1613989752425307",
"url": "https://ci.chromium.org/b/8854602797772562768",
"utcnow_ts": "1613989752546813"
}
}
[10:29:12.558] Task URL: https://ci.chromium.org/b/8854602797772562768
[10:29:12.558] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:2:0) after 1m0s
[10:29:16.489] Received PubSub notification, asking Buildbucket for the build status
[10:29:16.670] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:12.684] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:2:0)
[10:30:12.684] Timer tick, asking Buildbucket for the build status
[10:30:12.754] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:30:12.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:4:0) after 1m0s
[10:31:12.873] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:4:0)
[10:31:12.873] Timer tick, asking Buildbucket for the build status
[10:31:12.972] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:31:12.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:5:0) after 1m0s
[10:32:13.073] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:5:0)
[10:32:13.073] Timer tick, asking Buildbucket for the build status
[10:32:13.151] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:32:13.151] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:6:0) after 1m0s
[10:33:13.240] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:6:0)
[10:33:13.240] Timer tick, asking Buildbucket for the build status
[10:33:13.350] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:33:13.350] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:7:0) after 1m0s
[10:34:13.444] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:7:0)
[10:34:13.444] Timer tick, asking Buildbucket for the build status
[10:34:13.618] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:34:13.618] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:8:0) after 1m0s
[10:35:13.738] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:8:0)
[10:35:13.738] Timer tick, asking Buildbucket for the build status
[10:35:13.828] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:35:13.828] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:9:0) after 1m0s
[10:36:13.976] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:9:0)
[10:36:13.976] Timer tick, asking Buildbucket for the build status
[10:36:14.073] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:36:14.073] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:10:0) after 1m0s
[10:37:14.134] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:10:0)
[10:37:14.134] Timer tick, asking Buildbucket for the build status
[10:37:14.200] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:37:14.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:11:0) after 1m0s
[10:38:14.288] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:11:0)
[10:38:14.288] Timer tick, asking Buildbucket for the build status
[10:38:14.367] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:38:14.367] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:12:0) after 1m0s
[10:39:14.445] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:12:0)
[10:39:14.445] Timer tick, asking Buildbucket for the build status
[10:39:14.702] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:39:14.702] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:13:0) after 1m0s
[10:40:14.773] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:13:0)
[10:40:14.773] Timer tick, asking Buildbucket for the build status
[10:40:14.839] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:40:14.839] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:14:0) after 1m0s
[10:41:14.942] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:14:0)
[10:41:14.942] Timer tick, asking Buildbucket for the build status
[10:41:15.028] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:41:15.028] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:15:0) after 1m0s
[10:42:15.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:15:0)
[10:42:15.064] Timer tick, asking Buildbucket for the build status
[10:42:15.153] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:42:15.153] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:16:0) after 1m0s
[10:43:15.190] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:16:0)
[10:43:15.190] Timer tick, asking Buildbucket for the build status
[10:43:15.275] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:43:15.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:17:0) after 1m0s
[10:44:15.333] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:17:0)
[10:44:15.333] Timer tick, asking Buildbucket for the build status
[10:44:15.413] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:44:15.413] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:18:0) after 1m0s
[10:45:15.424] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:18:0)
[10:45:15.424] Timer tick, asking Buildbucket for the build status
[10:45:15.621] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:45:15.621] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:19:0) after 1m0s
[10:46:15.706] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:19:0)
[10:46:15.706] Timer tick, asking Buildbucket for the build status
[10:46:15.786] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:46:15.787] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:20:0) after 1m0s
[10:47:15.872] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:20:0)
[10:47:15.872] Timer tick, asking Buildbucket for the build status
[10:47:15.955] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:47:15.955] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:21:0) after 1m0s
[10:48:15.959] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:21:0)
[10:48:15.959] Timer tick, asking Buildbucket for the build status
[10:48:16.395] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:48:16.395] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:22:0) after 1m0s
[10:49:16.452] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:22:0)
[10:49:16.452] Timer tick, asking Buildbucket for the build status
[10:49:16.554] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:49:16.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:23:0) after 1m0s
[10:50:16.563] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:23:0)
[10:50:16.563] Timer tick, asking Buildbucket for the build status
[10:50:16.654] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:50:16.654] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:24:0) after 1m0s
[10:51:17.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:24:0)
[10:51:17.454] Timer tick, asking Buildbucket for the build status
[10:51:17.814] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:51:17.814] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:25:0) after 1m0s
[10:52:17.892] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:25:0)
[10:52:17.892] Timer tick, asking Buildbucket for the build status
[10:52:18.230] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:52:18.230] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:26:0) after 1m0s
[10:53:18.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:26:0)
[10:53:18.235] Timer tick, asking Buildbucket for the build status
[10:53:19.147] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:53:19.147] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:27:0) after 1m0s
[10:54:19.146] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:27:0)
[10:54:19.146] Timer tick, asking Buildbucket for the build status
[10:54:19.277] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:54:19.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:28:0) after 1m0s
[10:55:19.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:28:0)
[10:55:19.315] Timer tick, asking Buildbucket for the build status
[10:55:19.409] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:55:19.409] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:29:0) after 1m0s
[10:56:19.397] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:29:0)
[10:56:19.397] Timer tick, asking Buildbucket for the build status
[10:56:19.718] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:56:19.718] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:30:0) after 1m0s
[10:57:19.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:30:0)
[10:57:19.733] Timer tick, asking Buildbucket for the build status
[10:57:19.823] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:57:19.823] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:31:0) after 1m0s
[10:58:19.801] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:31:0)
[10:58:19.801] Timer tick, asking Buildbucket for the build status
[10:58:19.876] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:58:19.876] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:32:0) after 1m0s
[10:59:19.890] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:32:0)
[10:59:19.890] Timer tick, asking Buildbucket for the build status
[10:59:19.989] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[10:59:19.989] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:33:0) after 1m0s
[11:00:20.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:33:0)
[11:00:20.089] Timer tick, asking Buildbucket for the build status
[11:00:20.226] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:00:20.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:34:0) after 1m0s
[11:01:20.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:34:0)
[11:01:20.228] Timer tick, asking Buildbucket for the build status
[11:01:20.403] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:01:20.403] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:35:0) after 1m0s
[11:02:20.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:35:0)
[11:02:20.469] Timer tick, asking Buildbucket for the build status
[11:02:20.615] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:02:20.615] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:36:0) after 1m0s
[11:03:20.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:36:0)
[11:03:20.661] Timer tick, asking Buildbucket for the build status
[11:03:20.932] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:03:20.932] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:37:0) after 1m0s
[11:04:20.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:37:0)
[11:04:20.949] Timer tick, asking Buildbucket for the build status
[11:04:21.043] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:04:21.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:38:0) after 1m0s
[11:05:21.133] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:38:0)
[11:05:21.133] Timer tick, asking Buildbucket for the build status
[11:05:21.240] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:05:21.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:39:0) after 1m0s
[11:06:21.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:39:0)
[11:06:21.266] Timer tick, asking Buildbucket for the build status
[11:06:21.426] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:06:21.428] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:40:0) after 1m0s
[11:07:21.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:40:0)
[11:07:21.449] Timer tick, asking Buildbucket for the build status
[11:07:21.775] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:07:21.775] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:41:0) after 1m0s
[11:08:21.809] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:41:0)
[11:08:21.809] Timer tick, asking Buildbucket for the build status
[11:08:21.884] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:08:21.884] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:42:0) after 1m0s
[11:09:21.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:42:0)
[11:09:21.906] Timer tick, asking Buildbucket for the build status
[11:09:22.011] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:09:22.011] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:43:0) after 1m0s
[11:10:22.101] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:43:0)
[11:10:22.101] Timer tick, asking Buildbucket for the build status
[11:10:22.365] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:10:22.365] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:44:0) after 1m0s
[11:11:22.415] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:44:0)
[11:11:22.415] Timer tick, asking Buildbucket for the build status
[11:11:22.614] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:11:22.614] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:45:0) after 1m0s
[11:12:22.660] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:45:0)
[11:12:22.660] Timer tick, asking Buildbucket for the build status
[11:12:22.748] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:12:22.748] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:46:0) after 1m0s
[11:13:22.733] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:46:0)
[11:13:22.733] Timer tick, asking Buildbucket for the build status
[11:13:22.829] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:13:22.829] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:47:0) after 1m0s
[11:14:22.847] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:47:0)
[11:14:22.847] Timer tick, asking Buildbucket for the build status
[11:14:23.029] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:14:23.030] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:48:0) after 1m0s
[11:15:23.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:48:0)
[11:15:23.026] Timer tick, asking Buildbucket for the build status
[11:15:23.116] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:15:23.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:49:0) after 1m0s
[11:16:23.186] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:49:0)
[11:16:23.186] Timer tick, asking Buildbucket for the build status
[11:16:23.316] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:16:23.316] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:50:0) after 1m0s
[11:17:23.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:50:0)
[11:17:23.334] Timer tick, asking Buildbucket for the build status
[11:17:23.418] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:17:23.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:51:0) after 1m0s
[11:18:23.480] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:51:0)
[11:18:23.480] Timer tick, asking Buildbucket for the build status
[11:18:23.653] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:18:23.653] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:52:0) after 1m0s
[11:19:23.757] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:52:0)
[11:19:23.757] Timer tick, asking Buildbucket for the build status
[11:19:23.879] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:19:23.879] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:53:0) after 1m0s
[11:20:23.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:53:0)
[11:20:23.947] Timer tick, asking Buildbucket for the build status
[11:20:24.123] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:20:24.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:54:0) after 1m0s
[11:21:24.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:54:0)
[11:21:24.235] Timer tick, asking Buildbucket for the build status
[11:21:24.323] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:21:24.323] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:55:0) after 1m0s
[11:22:24.395] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:55:0)
[11:22:24.395] Timer tick, asking Buildbucket for the build status
[11:22:24.490] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:22:24.490] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:56:0) after 1m0s
[11:23:24.551] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:56:0)
[11:23:24.551] Timer tick, asking Buildbucket for the build status
[11:23:24.637] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:23:24.637] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:57:0) after 1m0s
[11:24:25.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:57:0)
[11:24:25.322] Timer tick, asking Buildbucket for the build status
[11:24:25.444] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:24:25.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:58:0) after 1m0s
[11:25:25.716] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:58:0)
[11:25:25.716] Timer tick, asking Buildbucket for the build status
[11:25:25.818] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:25:25.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:59:0) after 1m0s
[11:26:25.903] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:59:0)
[11:26:25.903] Timer tick, asking Buildbucket for the build status
[11:26:25.992] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:26:25.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:60:0) after 1m0s
[11:27:26.205] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:60:0)
[11:27:26.205] Timer tick, asking Buildbucket for the build status
[11:27:26.507] Build 8854602797772562768: status "STARTED", result "", failure_reason "", cancelation_reason ""
[11:27:26.507] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9020032859677398032:61:0) after 1m0s
[11:27:31.356] Received PubSub notification, asking Buildbucket for the build status
[11:27:31.485] Build 8854602797772562768: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[11:27:31.485] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1613993250237940",
"created_by": "project:chromium",
"created_ts": "1613989751993286",
"id": "8854602797772562768",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"31e575207ffe69d0a3cf89c1ef24b822f49b5aa9\"}}",
"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\": \"31e575207ffe69d0a3cf89c1ef24b822f49b5aa9\", \"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-3-xpf4\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17468, \"clang_revision\": \"llvmorg-13-init-1559-g01b87444-2\", \"got_angle_revision\": \"196de52a3670581eee89544cea89bcb57da00751\", \"got_dawn_revision\": \"1258e245a978083cab0b707c84dea814eadb3fbb\", \"got_nacl_revision\": \"abf3277f295c6c87f0b103f291ed5e3e73933b76\", \"got_revision\": \"31e575207ffe69d0a3cf89c1ef24b822f49b5aa9\", \"got_revision_cp\": \"refs/heads/master@{#856228}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"d85a7a27b27269741a779a72f33436b83d98687c\", \"got_v8_revision\": \"f604046d660441e4b22b70a6239f5c826e8a45e7\", \"got_v8_revision_cp\": \"refs/heads/9.0.231@{#1}\", \"got_webrtc_revision\": \"753c76a705199ea752933f3688a8e4c8233dca53\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33298}\", \"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\": \"31e575207ffe69d0a3cf89c1ef24b822f49b5aa9\", \"swarm_hashes_refs/heads/master(at){#856228}_without_patch\": {\"absl_hardening_tests\": \"e04f1674c79d473a3e2ec7f43b98ab10afe8a948\", \"accessibility_unittests\": \"9f9e50b500aa4dcddf10a14dc16a66d09420b00c\", \"angle_unittests\": \"de9fe68f3e9c16ca2f82cf6681e2a18d49f74e45\", \"app_shell_unittests\": \"9241c650f77088a33f7ae676768ccc2c6394bf4b\", \"aura_unittests\": \"2b2d671c85a27939489aca25afe721c2f1a7333b\", \"base_unittests\": \"d4de829205ca3fc7b91e499dcabd70bfb2037f2c\", \"base_util_unittests\": \"4a24ae9205265c61bfb1a9220c14f27d1b9d590c\", \"blink_common_unittests\": \"4d05d6d12bb506b0a86d414e6b5e776c0f1c13da\", \"blink_fuzzer_unittests\": \"a0c308e5c0eddf557dd7fd60458c46134182cf1e\", \"blink_heap_unittests\": \"6544199bad0ccd95d22386fc890069834ae936b8\", \"blink_platform_unittests\": \"d4d821704f18f3d661179881951ecb3f7db4bba8\", \"blink_unittests\": \"0875f23d3916d625d9984732a55675dd2b34a0dd\", \"boringssl_crypto_tests\": \"2b0ecd2bff56d19ca45e48454a413c50a1b09777\", \"boringssl_ssl_tests\": \"8b3a5f3ddcb4b2e816e8227b339488d56dfa6f56\", \"browser_switcher_bho_unittests\": \"1211ef2ee8fb1f6eef648516423e73cdf0d102f9\", \"browser_tests\": \"8a1630704ee288b6f9ea77860ab14178590cf053\", \"capture_unittests\": \"29aba1030d37e201b7862c6300bcf2329e47c244\", \"cast_unittests\": \"6b5ba06faec682e90d96d35f880904a61217228f\", \"cc_unittests\": \"5853e113e188984f4c604957ebac26215bb0a878\", \"chrome_app_unittests\": \"3840a090050856cb5b02f6d5a07bc8a667059fc5\", \"chrome_cleaner_unittests\": \"55e2f47cd864115643d7551ea3c064e2464eb5fc\", \"chrome_elf_unittests\": \"c035170332f6290dbefc61739872e3d466fcff36\", \"chromedriver_unittests\": \"4312e1019073fca51904a0e2168cfabf6abec7d6\", \"components_browsertests\": \"8337c802c81a2939f9d15bf2ceffb92347c873d0\", \"components_unittests\": \"534297f9d0e052ff9179a05246b72420e1726294\", \"compositor_unittests\": \"e6f8f32a93f815b74a6313f370a813402c4dc8f1\", \"content_browsertests\": \"f8971cd75894284cb8f9df16cc3fadff42e68dfd\", \"content_unittests\": \"95f1e96b7530f2f048de35ee14a268386f2b7e30\", \"courgette_unittests\": \"3a8374677f99fe0e5c5776e38619bfada7d55a1d\", \"crashpad_tests\": \"7404d2fd388f46939e5fb883f9612ee165874e0f\", \"cronet_tests\": \"9a83294f13ad35a6cf82fdebc717dfbac040d35a\", \"cronet_unittests\": \"4979a12864a1c4e1330e7ce891d4369a2e32a4ad\", \"crypto_unittests\": \"bb7ab6223d44490aed5b625810f3bb94c6364428\", \"delayloads_unittests\": \"463bf9453173f56a124781fcd5dc754b8974c933\", \"device_unittests\": \"0965f6ba866bdc029b5eaef96735063d855cc155\", \"display_unittests\": \"0c4f5d584e2c1c5eeff2180855204802fdae2639\", \"elevation_service_unittests\": \"9a98da25fbbde188db6f296623f5ed7a1db39d7c\", \"events_unittests\": \"84c8929f4d1ccc914095692c5a32c66320ca2b65\", \"extensions_browsertests\": \"8d19267b1383a91cd8d1ec5ecd312fd6053dbdef\", \"extensions_unittests\": \"0f37cdf45d9daec06efedef4486c63cf9d35cbd8\", \"filesystem_service_unittests\": \"894e8d9357c459cbee6bb5caa66fe77b9dcf7971\", \"gcm_unit_tests\": \"24851e5cf10fc135255713fac97c693863cf0a73\", \"gcp_unittests\": \"a83845027f4e1443f10ef32c83c47b94cf4dd191\", \"gfx_unittests\": \"de4731e4450e4ae75f8402a6842184c59e009ca0\", \"gin_unittests\": \"9b32d8228456f18afefbe9dbbfb627d7abcb0941\", \"google_apis_unittests\": \"c7d3521637b26bdfac5393d97b0e280bd8bde35a\", \"gpu_unittests\": \"5b18d4d71fd58f73f1d8692827f07aecd2c2f69e\", \"gwp_asan_unittests\": \"c00f048445175ea5a75d5a5c9d055469a4f4a0e0\", \"headless_browsertests\": \"9123e1d8008a4a80690a57b889632bba167e6396\", \"headless_unittests\": \"a34f17b12a59cc5d312db5b8dc6708f85d5e4fce\", \"install_static_unittests\": \"8202ce095aef2ad17f9d4fb46b5808e883f0276e\", \"installer_util_unittests\": \"e33051b9f7b39e0b85d779b21bc9beb51ea2dc69\", \"interactive_ui_tests\": \"64aee225906830f5a792c1e8bea3f9671d59af57\", \"ipc_tests\": \"3d021fd03e790946e8fb17d066ac2cf55385e4cd\", \"jingle_unittests\": \"2387f7f8ccaf3b5e86c265186b2747625bcbec3a\", \"latency_unittests\": \"810a5adc2e5f3a5f4d7e0e85c967d34de4c093c6\", \"libjingle_xmpp_unittests\": \"1838b44993b93f95f7ac96d3353eea76732d49b2\", \"liburlpattern_unittests\": \"c0d6dbc61365d645cf1f0d3e3bac5a1d4c05a510\", \"media_blink_unittests\": \"b474e88a79b8bc369921d0b29ca8364b1a54115b\", \"media_unittests\": \"5944d816b0dda0093cd0186e300d27106c7fd347\", \"message_center_unittests\": \"6ed89d3b42202e7cdf8026837259a81dd674e728\", \"midi_unittests\": \"ef799d0780a0fd3a9692026fb544836f013c613c\", \"mojo_core_unittests\": \"f06ee34a8e63aa33bd666172a5eeb4cbdae01946\", \"mojo_unittests\": \"3cd2cbd0bac22c28a17710016a05c80db39cdfae\", \"nacl_loader_unittests\": \"dcec99863190a82c060544fe073040bd9e653944\", \"native_theme_unittests\": \"0b69c3d475061b683ff6b60101272ada5bceaba7\", \"net_unittests\": \"ee6230e4671b3460bde0ccfb2bf6bc122e624afc\", \"notification_helper_unittests\": \"62c7fcb2d50e2647989fed226596fb860b355484\", \"pdf_unittests\": \"554d92650a915b06b8b83dd9eaedb13a71304380\", \"perfetto_unittests\": \"b29ca25b2015564b48607123201865eb7a0deb08\", \"ppapi_unittests\": \"ecb397c3d03a3e174628701478bac98391e85387\", \"printing_unittests\": \"5502a8b58ed957ebb2af2ad33be903d2b8e07768\", \"remoting_unittests\": \"a70ba59da76a3cdac86ff53b38edc3fa70891b26\", \"sbox_integration_tests\": \"3995e50329370ea0713d1b92ee85bf47a43cc2fe\", \"sbox_unittests\": \"4f22d4da6535f959b0d550c8cf0b5aabb427af7f\", \"sbox_validation_tests\": \"c5e991b12786e38905ad66add06f53bdbb7abee0\", \"service_manager_unittests\": \"84e59031c18384eede41ef5a79400408a363417b\", \"services_unittests\": \"8593bb648fce16e8329a9b130b29fac3fffa8a6b\", \"setup_unittests\": \"e5b7ce1c0661e4138cd162daeca42af49e637f03\", \"shell_dialogs_unittests\": \"980bb35cfe07fcc562ba609b2a2ea0e87d7eedf8\", \"skia_unittests\": \"80c5fcbe9de85e38a92a4e3fcddf4efac133b38a\", \"snapshot_unittests\": \"90d624a775cff8eec21a78085452787970a08b65\", \"sql_unittests\": \"5b323fa2f39d43d5c9dc9b266449c526ff2bf153\", \"storage_unittests\": \"19f3f98cf97ad60ee89052112e7b48b8f6c6f603\", \"sync_integration_tests\": \"0e45cdbc1a12000c0a7081f04ac26cf47404a9ac\", \"traffic_annotation_auditor_unittests\": \"02f09d6c3eaa6fc6932b4efa8e8b7276d40335fd\", \"ui_base_unittests\": \"f925953ba8220ecfc2994d34cde79bad1f3f74db\", \"ui_touch_selection_unittests\": \"01dfd5d7b2aa606368663effa588711ab15f3163\", \"unit_tests\": \"2bd9ae6fb1ff0785c267e979c4166fa0384c9307\", \"updater_tests\": \"1f37f2faa15747e57c1a7cdb64aed1bdb6a38912\", \"url_unittests\": \"6902a7d04e4fa2df1a806861575fd250457a55e2\", \"views_unittests\": \"9de5032ac78adbfedf3a045945498953b59dcdbf\", \"viz_unittests\": \"2b54e44a267c1ab6233706c9417d3de45b8202a7\", \"vr_common_unittests\": \"f3ae785809da9cb548201b1424bea09fa934d524\", \"vr_pixeltests\": \"1eb16efa91805998a9189de8f7808585b75de956\", \"weblayer_browsertests\": \"df57d28afbace284e8c3edbb9d4edf9be64a7ceb\", \"weblayer_unittests\": \"351ea0208a466ef3dd0136b7cc5ffb6066862121\", \"wm_unittests\": \"1bac5ae92e15270740b8f0e4dec8d6a5aefea4d6\", \"wtf_unittests\": \"b5bceed4cc14e222126d3a9e4555b4fd703c22f6\", \"zlib_unittests\": \"d17ddf218a891d025d74dea3cd99a9a800ef8238\", \"zucchini_unittests\": \"9907891d20b99c8b09101c204aa768f0902fbb25\"}}, \"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-3-xpf4\"], \"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\": [\"5706-397711c\"], \"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": "1613989755283973",
"status": "COMPLETED",
"status_changed_ts": "1613993250586592",
"tags": [
"build_address:luci.chromium.ci/win-asan/17468",
"builder:win-asan",
"buildset:commit/git/31e575207ffe69d0a3cf89c1ef24b822f49b5aa9",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/31e575207ffe69d0a3cf89c1ef24b822f49b5aa9",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9020032859677398032",
"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/8854602797772562768/+/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:51e21ab904fbd810",
"user_agent:luci-scheduler"
],
"updated_ts": "1613993250590591",
"url": "https://ci.chromium.org/b/8854602797772562768",
"utcnow_ts": "1613993251473809"
}
}
[11:27:31.485] Invocation finished in 58m20.691970361s with status SUCCEEDED