[03:17:10.343] New invocation is queued and will start shortly
[03:17:11.631] Starting the invocation (attempt 1)
[03:17:11.736] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:17:11.736] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:17:11.736] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9022777948247343904",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"a633b1b0a46b65bae42d98885ab0233dbbf64d5c\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9022777948247343904",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/a633b1b0a46b65bae42d98885ab0233dbbf64d5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a633b1b0a46b65bae42d98885ab0233dbbf64d5c",
"gitiles_ref:refs/heads/master"
]
}
[03:17:14.703] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1611371832042932",
"id": "8857347885803114480",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a633b1b0a46b65bae42d98885ab0233dbbf64d5c\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1611371833911206",
"tags": [
"build_address:luci.chromium.ci/win-asan/16959",
"builder:win-asan",
"buildset:commit/git/a633b1b0a46b65bae42d98885ab0233dbbf64d5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a633b1b0a46b65bae42d98885ab0233dbbf64d5c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022777948247343904",
"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/8857347885803114480/+/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": "1611371833911353",
"url": "https://ci.chromium.org/b/8857347885803114480",
"utcnow_ts": "1611371834670211"
}
}
[03:17:14.703] Task URL: https://ci.chromium.org/b/8857347885803114480
[03:17:14.703] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:2:0) after 1m0s
[03:17:34.431] Received PubSub notification, asking Buildbucket for the build status
[03:17:34.543] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:14.726] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:2:0)
[03:18:14.726] Timer tick, asking Buildbucket for the build status
[03:18:14.850] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:14.850] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:4:0) after 1m0s
[03:19:14.869] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:4:0)
[03:19:14.869] Timer tick, asking Buildbucket for the build status
[03:19:15.029] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:15.029] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:5:0) after 1m0s
[03:20:15.036] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:5:0)
[03:20:15.036] Timer tick, asking Buildbucket for the build status
[03:20:15.248] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:15.248] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:6:0) after 1m0s
[03:21:15.268] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:6:0)
[03:21:15.268] Timer tick, asking Buildbucket for the build status
[03:21:15.475] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:15.475] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:7:0) after 1m0s
[03:22:15.525] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:7:0)
[03:22:15.525] Timer tick, asking Buildbucket for the build status
[03:22:15.676] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:15.676] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:8:0) after 1m0s
[03:23:15.748] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:8:0)
[03:23:15.748] Timer tick, asking Buildbucket for the build status
[03:23:15.904] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:15.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:9:0) after 1m0s
[03:24:15.973] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:9:0)
[03:24:15.973] Timer tick, asking Buildbucket for the build status
[03:24:16.170] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:16.170] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:10:0) after 1m0s
[03:25:16.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:10:0)
[03:25:16.265] Timer tick, asking Buildbucket for the build status
[03:25:16.373] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:16.373] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:11:0) after 1m0s
[03:26:16.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:11:0)
[03:26:16.474] Timer tick, asking Buildbucket for the build status
[03:26:16.721] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:16.721] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:12:0) after 1m0s
[03:27:16.753] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:12:0)
[03:27:16.753] Timer tick, asking Buildbucket for the build status
[03:27:16.909] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:16.909] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:13:0) after 1m0s
[03:28:16.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:13:0)
[03:28:16.979] Timer tick, asking Buildbucket for the build status
[03:28:17.096] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:17.096] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:14:0) after 1m0s
[03:29:17.089] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:14:0)
[03:29:17.089] Timer tick, asking Buildbucket for the build status
[03:29:17.319] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:17.319] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:15:0) after 1m0s
[03:30:17.366] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:15:0)
[03:30:17.366] Timer tick, asking Buildbucket for the build status
[03:30:17.527] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:17.527] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:16:0) after 1m0s
[03:31:17.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:16:0)
[03:31:17.548] Timer tick, asking Buildbucket for the build status
[03:31:17.695] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:17.695] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:17:0) after 1m0s
[03:32:17.717] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:17:0)
[03:32:17.717] Timer tick, asking Buildbucket for the build status
[03:32:17.818] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:17.818] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:18:0) after 1m0s
[03:33:17.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:18:0)
[03:33:17.831] Timer tick, asking Buildbucket for the build status
[03:33:18.104] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:18.104] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:19:0) after 1m0s
[03:34:18.157] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:19:0)
[03:34:18.157] Timer tick, asking Buildbucket for the build status
[03:34:18.265] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:18.265] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:20:0) after 1m0s
[03:35:18.295] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:20:0)
[03:35:18.295] Timer tick, asking Buildbucket for the build status
[03:35:18.394] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:18.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:21:0) after 1m0s
[03:36:18.417] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:21:0)
[03:36:18.417] Timer tick, asking Buildbucket for the build status
[03:36:18.724] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:18.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:22:0) after 1m0s
[03:37:18.744] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:22:0)
[03:37:18.744] Timer tick, asking Buildbucket for the build status
[03:37:18.954] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:18.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:23:0) after 1m0s
[03:38:18.975] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:23:0)
[03:38:18.975] Timer tick, asking Buildbucket for the build status
[03:38:19.442] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:19.442] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:24:0) after 1m0s
[03:39:19.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:24:0)
[03:39:19.483] Timer tick, asking Buildbucket for the build status
[03:39:19.594] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:19.594] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:25:0) after 1m0s
[03:40:19.626] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:25:0)
[03:40:19.626] Timer tick, asking Buildbucket for the build status
[03:40:20.889] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:20.889] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:26:0) after 1m0s
[03:41:20.923] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:26:0)
[03:41:20.923] Timer tick, asking Buildbucket for the build status
[03:41:21.019] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:21.019] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:27:0) after 1m0s
[03:42:21.045] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:27:0)
[03:42:21.045] Timer tick, asking Buildbucket for the build status
[03:42:21.206] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:21.206] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:28:0) after 1m0s
[03:43:21.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:28:0)
[03:43:21.231] Timer tick, asking Buildbucket for the build status
[03:43:21.745] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:21.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:29:0) after 1m0s
[03:44:21.766] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:29:0)
[03:44:21.766] Timer tick, asking Buildbucket for the build status
[03:44:21.943] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:21.943] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:30:0) after 1m0s
[03:45:21.965] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:30:0)
[03:45:21.965] Timer tick, asking Buildbucket for the build status
[03:45:22.140] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:22.140] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:31:0) after 1m0s
[03:46:22.249] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:31:0)
[03:46:22.249] Timer tick, asking Buildbucket for the build status
[03:46:22.548] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:22.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:32:0) after 1m0s
[03:47:22.617] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:32:0)
[03:47:22.617] Timer tick, asking Buildbucket for the build status
[03:47:23.438] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:23.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:33:0) after 1m0s
[03:48:23.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:33:0)
[03:48:23.453] Timer tick, asking Buildbucket for the build status
[03:48:23.629] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:23.629] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:34:0) after 1m0s
[03:49:23.696] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:34:0)
[03:49:23.696] Timer tick, asking Buildbucket for the build status
[03:49:23.767] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:23.767] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:35:0) after 1m0s
[03:50:23.836] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:35:0)
[03:50:23.836] Timer tick, asking Buildbucket for the build status
[03:50:23.954] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:23.954] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:36:0) after 1m0s
[03:51:23.972] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:36:0)
[03:51:23.972] Timer tick, asking Buildbucket for the build status
[03:51:24.116] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:24.116] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:37:0) after 1m0s
[03:52:24.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:37:0)
[03:52:24.138] Timer tick, asking Buildbucket for the build status
[03:52:24.196] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:24.196] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:38:0) after 1m0s
[03:53:24.228] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:38:0)
[03:53:24.228] Timer tick, asking Buildbucket for the build status
[03:53:24.746] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:24.746] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:39:0) after 1m0s
[03:54:24.778] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:39:0)
[03:54:24.778] Timer tick, asking Buildbucket for the build status
[03:54:24.891] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:24.891] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:40:0) after 1m0s
[03:55:24.914] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:40:0)
[03:55:24.914] Timer tick, asking Buildbucket for the build status
[03:55:25.129] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:25.129] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:41:0) after 1m0s
[03:56:25.156] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:41:0)
[03:56:25.156] Timer tick, asking Buildbucket for the build status
[03:56:25.241] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:25.241] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:42:0) after 1m0s
[03:57:25.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:42:0)
[03:57:25.266] Timer tick, asking Buildbucket for the build status
[03:57:25.391] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:25.391] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:43:0) after 1m0s
[03:58:25.422] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:43:0)
[03:58:25.422] Timer tick, asking Buildbucket for the build status
[03:58:25.528] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:25.528] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:44:0) after 1m0s
[03:59:25.549] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:44:0)
[03:59:25.549] Timer tick, asking Buildbucket for the build status
[03:59:25.675] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:25.675] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:45:0) after 1m0s
[04:00:25.755] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:45:0)
[04:00:25.755] Timer tick, asking Buildbucket for the build status
[04:00:26.056] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:26.056] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:46:0) after 1m0s
[04:01:26.094] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:46:0)
[04:01:26.094] Timer tick, asking Buildbucket for the build status
[04:01:26.200] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:26.200] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:47:0) after 1m0s
[04:02:26.235] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:47:0)
[04:02:26.235] Timer tick, asking Buildbucket for the build status
[04:02:26.404] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:26.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:48:0) after 1m0s
[04:03:26.490] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:48:0)
[04:03:26.490] Timer tick, asking Buildbucket for the build status
[04:03:26.613] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:26.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:49:0) after 1m0s
[04:04:26.636] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:49:0)
[04:04:26.636] Timer tick, asking Buildbucket for the build status
[04:04:26.753] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:26.753] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:50:0) after 1m0s
[04:05:26.785] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:50:0)
[04:05:26.785] Timer tick, asking Buildbucket for the build status
[04:05:27.362] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:27.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:51:0) after 1m0s
[04:06:27.388] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:51:0)
[04:06:27.388] Timer tick, asking Buildbucket for the build status
[04:06:27.473] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:27.473] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:52:0) after 1m0s
[04:07:27.558] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:52:0)
[04:07:27.558] Timer tick, asking Buildbucket for the build status
[04:07:28.103] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:28.103] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:53:0) after 1m0s
[04:08:28.124] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:53:0)
[04:08:28.124] Timer tick, asking Buildbucket for the build status
[04:08:28.852] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:28.852] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:54:0) after 1m0s
[04:09:28.906] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:54:0)
[04:09:28.906] Timer tick, asking Buildbucket for the build status
[04:09:28.987] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:09:28.987] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:55:0) after 1m0s
[04:10:29.046] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:55:0)
[04:10:29.046] Timer tick, asking Buildbucket for the build status
[04:10:29.190] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:10:29.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:56:0) after 1m0s
[04:11:29.232] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:56:0)
[04:11:29.232] Timer tick, asking Buildbucket for the build status
[04:11:29.394] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:11:29.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:57:0) after 1m0s
[04:12:29.453] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:57:0)
[04:12:29.453] Timer tick, asking Buildbucket for the build status
[04:12:29.521] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:12:29.521] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:58:0) after 1m0s
[04:13:29.538] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:58:0)
[04:13:29.538] Timer tick, asking Buildbucket for the build status
[04:13:29.744] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:29.744] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:59:0) after 1m0s
[04:14:29.796] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:59:0)
[04:14:29.796] Timer tick, asking Buildbucket for the build status
[04:14:29.881] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:14:29.881] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:60:0) after 1m0s
[04:15:29.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:60:0)
[04:15:29.897] Timer tick, asking Buildbucket for the build status
[04:15:30.021] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:15:30.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:61:0) after 1m0s
[04:16:30.064] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:61:0)
[04:16:30.064] Timer tick, asking Buildbucket for the build status
[04:16:30.180] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:16:30.180] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:62:0) after 1m0s
[04:17:30.214] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:62:0)
[04:17:30.214] Timer tick, asking Buildbucket for the build status
[04:17:30.320] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:17:30.320] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:63:0) after 1m0s
[04:18:30.329] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:63:0)
[04:18:30.329] Timer tick, asking Buildbucket for the build status
[04:18:30.904] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:18:30.904] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:64:0) after 1m0s
[04:19:31.128] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:64:0)
[04:19:31.128] Timer tick, asking Buildbucket for the build status
[04:19:31.267] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:19:31.267] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:65:0) after 1m0s
[04:20:31.304] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:65:0)
[04:20:31.305] Timer tick, asking Buildbucket for the build status
[04:20:31.476] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:20:31.476] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:66:0) after 1m0s
[04:21:31.531] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:66:0)
[04:21:31.531] Timer tick, asking Buildbucket for the build status
[04:21:31.710] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:21:31.710] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:67:0) after 1m0s
[04:22:31.824] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:67:0)
[04:22:31.824] Timer tick, asking Buildbucket for the build status
[04:22:35.032] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:22:35.032] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:68:0) after 1m0s
[04:23:35.061] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:68:0)
[04:23:35.061] Timer tick, asking Buildbucket for the build status
[04:23:35.157] Build 8857347885803114480: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:23:35.157] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9022777948247343904:69:0) after 1m0s
[04:24:08.711] Received PubSub notification, asking Buildbucket for the build status
[04:24:08.837] Build 8857347885803114480: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[04:24:08.837] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1611375847191511",
"created_by": "project:chromium",
"created_ts": "1611371832042932",
"id": "8857347885803114480",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"a633b1b0a46b65bae42d98885ab0233dbbf64d5c\"}}",
"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\": \"a633b1b0a46b65bae42d98885ab0233dbbf64d5c\", \"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-2zr0\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16959, \"clang_revision\": \"llvmorg-12-init-17251-g6de48655-1\", \"got_angle_revision\": \"3c3b82abcf0a5c18b4d97ae49726045ce6d3b832\", \"got_dawn_revision\": \"f1f8394de04ea9532de763dfb2f968fc9610683b\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"a633b1b0a46b65bae42d98885ab0233dbbf64d5c\", \"got_revision_cp\": \"refs/heads/master@{#846474}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"413f7cb17c77c95e4cc3ba141f3c0f049ac2ecb2\", \"got_v8_revision\": \"83f44c53679624b80370f5d8383be83abf0be4c4\", \"got_v8_revision_cp\": \"refs/heads/9.0.56@{#1}\", \"got_webrtc_revision\": \"8c007fffead0f29650d6e34c10c85605e4dd33df\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33061}\", \"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\": \"a633b1b0a46b65bae42d98885ab0233dbbf64d5c\", \"swarm_hashes_refs/heads/master(at){#846474}_without_patch\": {\"absl_hardening_tests\": \"1f2a710a685ac73b35047b1818ef70ed124bf33c\", \"accessibility_unittests\": \"0b19ba4811deab071d3e85e892c909dfc70f6f9f\", \"angle_unittests\": \"069c1c9c80c861d5f0030d422cdff94d07317eba\", \"app_shell_unittests\": \"b1c48f7140226f86efab4c913affe328e771c638\", \"aura_unittests\": \"de916ed476f931a7dc3d5c657b8b09b35100bc4b\", \"base_unittests\": \"d8848e28944c271402872478c375c53cb26ed446\", \"base_util_unittests\": \"9bf07a1a7a634ed272988db3c91c890b2ec5e7a8\", \"blink_common_unittests\": \"dd5e06bff024637a5ade007737cb8dad1b021dfa\", \"blink_fuzzer_unittests\": \"40806332c797f04370ffe2723f80c465870a3315\", \"blink_heap_unittests\": \"c1d65117ccc5853009181c7803c84543c08adef9\", \"blink_platform_unittests\": \"4b1409371c5c8c2d92634275aead58cd50d37007\", \"blink_unittests\": \"29f93cd9aec9a8eadbcb1d0688a35a6739448b70\", \"boringssl_crypto_tests\": \"3d92088223316e0de4cd3a7dc50aaf695ac5258f\", \"boringssl_ssl_tests\": \"fce259e3006c34c2b95622e11032abf419efc721\", \"browser_switcher_bho_unittests\": \"5c3794f88ec637239bd1bc0ad82f29c5fb18f1ce\", \"browser_tests\": \"97da43e1f3a18b1d193a36c3ee9efb96d65aad97\", \"capture_unittests\": \"04feef238d44bb2e68a637e6601b0f1089c8f78d\", \"cast_unittests\": \"2abd7323f7305cd0834b66a04ac6612ef52edf7f\", \"cc_unittests\": \"e0e3ce92d012c62ea57a701b5cfa7c914b4a94ad\", \"chrome_app_unittests\": \"82bd326fb7202516904904b4be83d668119e6fcd\", \"chrome_cleaner_unittests\": \"927d9c8834cc1e99dde1981c44a6ec8f93d2abf3\", \"chrome_elf_unittests\": \"7830d56f7a63711de885e9975588f6e3d1c27b9c\", \"chromedriver_unittests\": \"01dc8db05c23f3561504649c9fc511d84e600b79\", \"components_browsertests\": \"7a008d4a553063da112d0f0ea691ee1c64b7d22d\", \"components_unittests\": \"8cfd3dc4929e40822a7dcb5ba36f6d5bb7977604\", \"compositor_unittests\": \"5cdb1f868f3b1a5b96225a4891e27a4a3506d620\", \"content_browsertests\": \"cbda4116609203c189f229b16326cac7a7b55e2a\", \"content_unittests\": \"66d83550c77609fd971121217a09c3770476dbc2\", \"courgette_unittests\": \"a2d2e2239b7d22e3b0ba41f472e09f5b23561dde\", \"crashpad_tests\": \"dc7f13cd1fd56d2cd76af1088da017ceafc7ba51\", \"cronet_tests\": \"a69cc245c8f62b320a685b0fe32eed1a3296e45f\", \"cronet_unittests\": \"7b4534a54a6ebaf1209afb3d49971643867e249b\", \"crypto_unittests\": \"0dc89e8571a84296c9811a6bd97927b6cf192e8f\", \"delayloads_unittests\": \"470417e7bbc0828799633ae86bebf0dfed2e208f\", \"device_unittests\": \"7182253ef87c1d22c8d241420cff3c26f20f6b4e\", \"display_unittests\": \"2e4612f07e667ef37e026f88b09e962102dd7b6c\", \"elevation_service_unittests\": \"2aac751806e8725d0b6819475d999151380285f2\", \"events_unittests\": \"45e5a2ad54a91a43fe6fd80ade7142b6f6ca0081\", \"extensions_browsertests\": \"6b135e90c422e0aa1c8f5b4b3b513200ecb1f34e\", \"extensions_unittests\": \"d971a0b0445df210dfe12779df787696c9df5ecb\", \"filesystem_service_unittests\": \"6c3ad0de635a1e13ce8548bc6bd72928b64aecdc\", \"gcm_unit_tests\": \"9ffc1e1a94f41a42be506cd25237e593e063d973\", \"gcp_unittests\": \"1441147e95203656931763ce7b4b5bef4dbbc93f\", \"gfx_unittests\": \"bf0655d21b69ea2682da8cce35666512084c26d1\", \"gin_unittests\": \"933aeb326000fa469c5e9ad63740175d92cd73a9\", \"google_apis_unittests\": \"e27940a2963025d9585014cf7de234c4a8211157\", \"gpu_unittests\": \"966a5cb3053f60ba71361cb6b8d0a96c3f0a91ae\", \"gwp_asan_unittests\": \"30f8313b9ab2f78b9f4cdd226a780db923ced183\", \"headless_browsertests\": \"2f721e46c76aea08c8503812b4cb1ce2bbe9cea7\", \"headless_unittests\": \"fbafbb84bb185d9a0bdcd8561145fad712071562\", \"install_static_unittests\": \"bd9aad33630318fff2f1e9baf56416319667c113\", \"installer_util_unittests\": \"66679ae97e2b717f1dfae0fff3db1df52ccfa691\", \"interactive_ui_tests\": \"19a9bdc23d4ebc8ae20194fd5118f637e414657e\", \"ipc_tests\": \"496219117c8235d6ecc7c7b24aa3debef50ff0a9\", \"jingle_unittests\": \"934a18c7d868eb104623a4a752d473204cd847bc\", \"latency_unittests\": \"6ee09b5bdd4dd77f01b6574c0c276483976aa3b3\", \"libjingle_xmpp_unittests\": \"1367ca97893cee47c5d71299dd2687e252a9c502\", \"liburlpattern_unittests\": \"9d811e5b616542cbe0210fdff76c31068fdfe2a2\", \"media_blink_unittests\": \"082e9b3062600644aa8956eed5690db02e105871\", \"media_unittests\": \"4a199ce4ae1693aef72d1b7c901e5ef5a9ba63dd\", \"message_center_unittests\": \"c64daef9d1368e733fda72933a15e359795777df\", \"midi_unittests\": \"bf12817260a8d2932247234a34c8f1da2178854a\", \"mojo_core_unittests\": \"df8edf8260ced679732c2ee056270feaeaab2edf\", \"mojo_unittests\": \"1e3aeae5ede126a6cd5d18b56713f85b58a3e489\", \"nacl_loader_unittests\": \"9972ce85a443110ea8f854414aafcd97130166d5\", \"native_theme_unittests\": \"a7c8b40e74db52a0b9e3d56714b21c85ecce35d9\", \"net_unittests\": \"328cdbfdc9aa9a9f766562745b5415921536e2fb\", \"notification_helper_unittests\": \"72b9f8f96e28af9ad6eeb8b57654129ba568221a\", \"pdf_unittests\": \"0df3b50d1a6aadae4a7c2cff0c6f4251dd267f72\", \"perfetto_unittests\": \"99d68615a6d8bdd86fedf07ddb72f557d7b804fd\", \"ppapi_unittests\": \"d0ee2254f040e118e1435a25b350104cf9920c8a\", \"printing_unittests\": \"b6cafead142b4fa95b7a818c15d0129257c38ee1\", \"remoting_unittests\": \"b3c7196ef2219f19930a4039322201aa2d4284ae\", \"sbox_integration_tests\": \"362400fca18f6eb3dc8b0aaaa7acae27bb72f8fa\", \"sbox_unittests\": \"346139aa123aabada021834405b492381ac48b30\", \"sbox_validation_tests\": \"2c47a7279953de0af7cbb7eb52f3771ae8b9b70f\", \"service_manager_unittests\": \"780b76faaf38129b245c86143d344f3032ff9481\", \"services_unittests\": \"723cf30bf8d95cbee0830472c537a894bf8bc340\", \"setup_unittests\": \"f5eaac5923a69c5d98f1b1d145d0077db8f98183\", \"shell_dialogs_unittests\": \"e06475f046eb3f60b019c8093b6474f28d4ac817\", \"skia_unittests\": \"cce190ff43244b8a1f2cd3fc93c02aad103f0674\", \"snapshot_unittests\": \"fdde909b36d97e5ea9101986dba91b5772190911\", \"sql_unittests\": \"4bdb684ccd1bd6fa2acaa4e9c4df7c254ae2d8e5\", \"storage_unittests\": \"24f6f81dc0efe669289cb07f9184958644d1624a\", \"sync_integration_tests\": \"873821a63f651e6275595faf810205889ebaaed7\", \"traffic_annotation_auditor_unittests\": \"d549b8a97aba2d2b83fbe09db236bfd9c9753bbd\", \"ui_base_unittests\": \"5282d83a9434f2e697a2a041e0af8457aa704513\", \"ui_touch_selection_unittests\": \"c975c1c8f91ece64294bb44a9f93cda82e1496b1\", \"unit_tests\": \"44f5f82af6291268cac4773585f0882fed1ca8be\", \"updater_tests\": \"90b3eb70225dbf9ac8ced5df278d024b494d2567\", \"url_unittests\": \"96174a01381cfed91e43a1822511b646c4e30661\", \"views_unittests\": \"dd41787979476baf253f4dbb38d698137f5e405a\", \"viz_unittests\": \"92b5c09f2787fdd2e80763c2b859f395cb58dcc6\", \"vr_common_unittests\": \"914af5705080197d89d8531f9061b88fb1b9151a\", \"vr_pixeltests\": \"4ebec610256df36fcf6b9e9196c59695889f1d6e\", \"weblayer_browsertests\": \"9fac41e5ecb4e0c5d28b8bee0c9de41346d5ff46\", \"weblayer_unittests\": \"9d5e41f33a4de2d9567d31cc2fc4036cf26ebd76\", \"wm_unittests\": \"8c3265cbf46df3aa71ae070710cd48175d1ec65e\", \"wtf_unittests\": \"95646723638172281c224182be57714839030616\", \"zlib_unittests\": \"a00dc59ab6d5709883183d97c12c7376b66062a8\", \"zucchini_unittests\": \"38d2844117cfb1def6325f047d5b35fd6b3096ee\"}}, \"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-2-2zr0\"], \"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\": [\"5671-1d9dea8\"], \"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": "1611371853545366",
"status": "COMPLETED",
"status_changed_ts": "1611375847962825",
"tags": [
"build_address:luci.chromium.ci/win-asan/16959",
"builder:win-asan",
"buildset:commit/git/a633b1b0a46b65bae42d98885ab0233dbbf64d5c",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a633b1b0a46b65bae42d98885ab0233dbbf64d5c",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9022777948247343904",
"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/8857347885803114480/+/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:514610a58e7e0310",
"user_agent:luci-scheduler"
],
"updated_ts": "1611375847967413",
"url": "https://ci.chromium.org/b/8857347885803114480",
"utcnow_ts": "1611375848826140"
}
}
[04:24:08.837] Invocation finished in 1h6m58.510255794s with status SUCCEEDED