[17:16:20.736] New invocation is queued and will start shortly
[17:16:21.814] Starting the invocation (attempt 1)
[17:16:21.858] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:16:21.859] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:16:21.859] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9030063506312682272",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"6ff787db0ddad97d7e6401692ba538fbab3e9010\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9030063506312682272",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/6ff787db0ddad97d7e6401692ba538fbab3e9010",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ff787db0ddad97d7e6401692ba538fbab3e9010",
"gitiles_ref:refs/heads/master"
]
}
[17:16:22.810] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"created_by": "project:chromium",
"created_ts": "1604423782000551",
"id": "8864633444323356704",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ff787db0ddad97d7e6401692ba538fbab3e9010\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1604423782506436",
"tags": [
"build_address:luci.chromium.ci/win-asan/15438",
"builder:win-asan",
"buildset:commit/git/6ff787db0ddad97d7e6401692ba538fbab3e9010",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ff787db0ddad97d7e6401692ba538fbab3e9010",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030063506312682272",
"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/8864633444323356704/+/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": "1604423782506600",
"url": "https://ci.chromium.org/b/8864633444323356704",
"utcnow_ts": "1604423782802421"
}
}
[17:16:22.810] Task URL: https://ci.chromium.org/b/8864633444323356704
[17:16:22.810] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:2:0) after 1m0s
[17:16:26.330] Received PubSub notification, asking Buildbucket for the build status
[17:16:26.597] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:22.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:2:0)
[17:17:22.829] Timer tick, asking Buildbucket for the build status
[17:17:22.942] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:22.942] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:4:0) after 1m0s
[17:18:23.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:4:0)
[17:18:23.031] Timer tick, asking Buildbucket for the build status
[17:18:23.303] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:23.303] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:5:0) after 1m0s
[17:19:23.317] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:5:0)
[17:19:23.317] Timer tick, asking Buildbucket for the build status
[17:19:23.555] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:23.555] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:6:0) after 1m0s
[17:20:23.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:6:0)
[17:20:23.634] Timer tick, asking Buildbucket for the build status
[17:20:23.820] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:23.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:7:0) after 1m0s
[17:21:23.855] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:7:0)
[17:21:23.855] Timer tick, asking Buildbucket for the build status
[17:21:24.253] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:24.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:8:0) after 1m0s
[17:22:24.313] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:8:0)
[17:22:24.313] Timer tick, asking Buildbucket for the build status
[17:22:24.796] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:24.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:9:0) after 1m0s
[17:23:25.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:9:0)
[17:23:25.077] Timer tick, asking Buildbucket for the build status
[17:23:25.183] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:25.183] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:10:0) after 1m0s
[17:24:25.231] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:10:0)
[17:24:25.231] Timer tick, asking Buildbucket for the build status
[17:24:25.318] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:25.318] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:11:0) after 1m0s
[17:25:25.491] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:11:0)
[17:25:25.491] Timer tick, asking Buildbucket for the build status
[17:25:25.589] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:25.589] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:12:0) after 1m0s
[17:26:25.676] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:12:0)
[17:26:25.676] Timer tick, asking Buildbucket for the build status
[17:26:25.925] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:25.925] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:13:0) after 1m0s
[17:27:26.008] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:13:0)
[17:27:26.008] Timer tick, asking Buildbucket for the build status
[17:27:26.082] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:26.082] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:14:0) after 1m0s
[17:28:26.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:14:0)
[17:28:26.110] Timer tick, asking Buildbucket for the build status
[17:28:26.204] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:26.204] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:15:0) after 1m0s
[17:29:26.242] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:15:0)
[17:29:26.242] Timer tick, asking Buildbucket for the build status
[17:29:27.277] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:27.277] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:16:0) after 1m0s
[17:30:27.334] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:16:0)
[17:30:27.334] Timer tick, asking Buildbucket for the build status
[17:30:27.545] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:27.545] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:17:0) after 1m0s
[17:31:27.614] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:17:0)
[17:31:27.614] Timer tick, asking Buildbucket for the build status
[17:31:27.827] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:27.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:18:0) after 1m0s
[17:32:28.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:18:0)
[17:32:28.066] Timer tick, asking Buildbucket for the build status
[17:32:28.430] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:28.430] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:19:0) after 1m0s
[17:33:28.552] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:19:0)
[17:33:28.552] Timer tick, asking Buildbucket for the build status
[17:33:28.754] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:28.754] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:20:0) after 1m0s
[17:34:28.850] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:20:0)
[17:34:28.850] Timer tick, asking Buildbucket for the build status
[17:34:29.175] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:29.175] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:21:0) after 1m0s
[17:35:29.322] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:21:0)
[17:35:29.322] Timer tick, asking Buildbucket for the build status
[17:35:29.506] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:29.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:22:0) after 1m0s
[17:36:29.638] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:22:0)
[17:36:29.638] Timer tick, asking Buildbucket for the build status
[17:36:29.785] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:29.785] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:23:0) after 1m0s
[17:37:29.880] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:23:0)
[17:37:29.880] Timer tick, asking Buildbucket for the build status
[17:37:30.086] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:30.086] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:24:0) after 1m0s
[17:38:30.910] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:24:0)
[17:38:30.910] Timer tick, asking Buildbucket for the build status
[17:38:31.172] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:31.172] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:25:0) after 1m0s
[17:39:31.210] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:25:0)
[17:39:31.210] Timer tick, asking Buildbucket for the build status
[17:39:31.339] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:31.339] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:26:0) after 1m0s
[17:40:31.410] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:26:0)
[17:40:31.410] Timer tick, asking Buildbucket for the build status
[17:40:31.584] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:31.584] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:27:0) after 1m0s
[17:41:31.662] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:27:0)
[17:41:31.662] Timer tick, asking Buildbucket for the build status
[17:41:31.797] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:31.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:28:0) after 1m0s
[17:42:31.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:28:0)
[17:42:31.936] Timer tick, asking Buildbucket for the build status
[17:42:32.386] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:32.386] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:29:0) after 1m0s
[17:43:33.413] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:29:0)
[17:43:33.413] Timer tick, asking Buildbucket for the build status
[17:43:34.185] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:34.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:30:0) after 1m0s
[17:44:34.218] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:30:0)
[17:44:34.218] Timer tick, asking Buildbucket for the build status
[17:44:34.592] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:34.592] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:31:0) after 1m0s
[17:45:34.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:31:0)
[17:45:34.634] Timer tick, asking Buildbucket for the build status
[17:45:35.041] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:35.041] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:32:0) after 1m0s
[17:46:35.138] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:32:0)
[17:46:35.138] Timer tick, asking Buildbucket for the build status
[17:46:35.294] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:35.294] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:33:0) after 1m0s
[17:47:35.367] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:33:0)
[17:47:35.368] Timer tick, asking Buildbucket for the build status
[17:47:37.336] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:37.336] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:34:0) after 1m0s
[17:48:37.357] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:34:0)
[17:48:37.357] Timer tick, asking Buildbucket for the build status
[17:48:37.521] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:37.522] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:35:0) after 1m0s
[17:49:37.597] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:35:0)
[17:49:37.597] Timer tick, asking Buildbucket for the build status
[17:49:37.745] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:37.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:36:0) after 1m0s
[17:50:37.884] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:36:0)
[17:50:37.884] Timer tick, asking Buildbucket for the build status
[17:50:37.983] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:37.983] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:37:0) after 1m0s
[17:51:38.093] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:37:0)
[17:51:38.093] Timer tick, asking Buildbucket for the build status
[17:51:38.260] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:38.260] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:38:0) after 1m0s
[17:52:38.358] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:38:0)
[17:52:38.358] Timer tick, asking Buildbucket for the build status
[17:52:38.461] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:38.461] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:39:0) after 1m0s
[17:53:38.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:39:0)
[17:53:38.599] Timer tick, asking Buildbucket for the build status
[17:53:41.606] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:41.606] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:40:0) after 1m0s
[17:54:41.679] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:40:0)
[17:54:41.679] Timer tick, asking Buildbucket for the build status
[17:54:41.820] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:41.820] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:41:0) after 1m0s
[17:55:41.825] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:41:0)
[17:55:41.826] Timer tick, asking Buildbucket for the build status
[17:55:42.000] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:42.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:42:0) after 1m0s
[17:56:42.037] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:42:0)
[17:56:42.037] Timer tick, asking Buildbucket for the build status
[17:56:42.167] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:42.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:43:0) after 1m0s
[17:57:42.222] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:43:0)
[17:57:42.222] Timer tick, asking Buildbucket for the build status
[17:57:42.404] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:42.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:44:0) after 1m0s
[17:58:42.700] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:44:0)
[17:58:42.700] Timer tick, asking Buildbucket for the build status
[17:58:42.806] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:42.806] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:45:0) after 1m0s
[17:59:43.116] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:45:0)
[17:59:43.116] Timer tick, asking Buildbucket for the build status
[17:59:43.223] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:43.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:46:0) after 1m0s
[18:00:43.305] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:46:0)
[18:00:43.305] Timer tick, asking Buildbucket for the build status
[18:00:43.405] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:43.405] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:47:0) after 1m0s
[18:01:43.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:47:0)
[18:01:43.545] Timer tick, asking Buildbucket for the build status
[18:01:43.793] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:43.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:48:0) after 1m0s
[18:02:43.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:48:0)
[18:02:43.939] Timer tick, asking Buildbucket for the build status
[18:02:44.240] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:44.240] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:49:0) after 1m0s
[18:03:44.266] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:49:0)
[18:03:44.266] Timer tick, asking Buildbucket for the build status
[18:03:47.404] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:47.404] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:50:0) after 1m0s
[18:04:47.463] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:50:0)
[18:04:47.463] Timer tick, asking Buildbucket for the build status
[18:04:47.610] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:04:47.610] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:51:0) after 1m0s
[18:05:47.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:51:0)
[18:05:47.644] Timer tick, asking Buildbucket for the build status
[18:05:47.731] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:05:47.731] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:52:0) after 1m0s
[18:06:47.779] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:52:0)
[18:06:47.779] Timer tick, asking Buildbucket for the build status
[18:06:48.108] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:06:48.108] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:53:0) after 1m0s
[18:07:48.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:53:0)
[18:07:48.215] Timer tick, asking Buildbucket for the build status
[18:07:48.526] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:07:48.526] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:54:0) after 1m0s
[18:08:48.590] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:54:0)
[18:08:48.590] Timer tick, asking Buildbucket for the build status
[18:08:48.778] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:08:48.778] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:55:0) after 1m0s
[18:09:48.782] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:55:0)
[18:09:48.782] Timer tick, asking Buildbucket for the build status
[18:09:49.142] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:09:49.142] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:56:0) after 1m0s
[18:10:49.187] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:56:0)
[18:10:49.187] Timer tick, asking Buildbucket for the build status
[18:10:49.344] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:10:49.344] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:57:0) after 1m0s
[18:11:49.448] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:57:0)
[18:11:49.448] Timer tick, asking Buildbucket for the build status
[18:11:49.576] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:11:49.576] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:58:0) after 1m0s
[18:12:49.698] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:58:0)
[18:12:49.698] Timer tick, asking Buildbucket for the build status
[18:12:49.849] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:12:49.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:59:0) after 1m0s
[18:13:49.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:59:0)
[18:13:49.897] Timer tick, asking Buildbucket for the build status
[18:13:50.058] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:13:50.058] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:60:0) after 1m0s
[18:14:50.081] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:60:0)
[18:14:50.081] Timer tick, asking Buildbucket for the build status
[18:14:50.168] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:14:50.169] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:61:0) after 1m0s
[18:15:50.250] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:61:0)
[18:15:50.250] Timer tick, asking Buildbucket for the build status
[18:15:50.385] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:15:50.385] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:62:0) after 1m0s
[18:16:50.627] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:62:0)
[18:16:50.627] Timer tick, asking Buildbucket for the build status
[18:16:50.803] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:16:50.803] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:63:0) after 1m0s
[18:17:50.949] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:63:0)
[18:17:50.949] Timer tick, asking Buildbucket for the build status
[18:17:51.148] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:17:51.148] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:64:0) after 1m0s
[18:18:51.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:64:0)
[18:18:51.348] Timer tick, asking Buildbucket for the build status
[18:18:51.638] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:18:51.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:65:0) after 1m0s
[18:19:51.728] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:65:0)
[18:19:51.728] Timer tick, asking Buildbucket for the build status
[18:19:51.886] Build 8864633444323356704: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:19:51.886] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9030063506312682272:66:0) after 1m0s
[18:20:24.540] Received PubSub notification, asking Buildbucket for the build status
[18:20:24.620] Build 8864633444323356704: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:20:24.620] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary": true,
"canary_preference": "CANARY",
"completed_ts": "1604427623574237",
"created_by": "project:chromium",
"created_ts": "1604423782000551",
"id": "8864633444323356704",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ff787db0ddad97d7e6401692ba538fbab3e9010\"}}",
"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\": \"6ff787db0ddad97d7e6401692ba538fbab3e9010\", \"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-5-vrjs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 15438, \"clang_revision\": \"llvmorg-12-init-5627-gf086e85e-3\", \"got_angle_revision\": \"6a4c9bbd518521d0471880c938635c8afefd3042\", \"got_dawn_revision\": \"43ef0a365b8443226916c090b88b3a0b09d6df96\", \"got_nacl_revision\": \"f9e6f257d1a0934dbeed3c5c8f83b651dc4f55e1\", \"got_revision\": \"6ff787db0ddad97d7e6401692ba538fbab3e9010\", \"got_revision_cp\": \"refs/heads/master@{#823576}\", \"got_swarming_client_revision\": \"d46ea7635f2911208268170512cb611412488fd8\", \"got_swiftshader_revision\": \"b5bf82693f85b53ce31846705d323764a582489b\", \"got_v8_revision\": \"ba40ae7eef4ddab929ee4ffc61fccc5da96ee00e\", \"got_v8_revision_cp\": \"refs/heads/8.8.223@{#1}\", \"got_webrtc_revision\": \"42cafa5696f516712bbe6709fedeedffac61544c\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32540}\", \"isolate_server\": \"https://isolateserver.appspot.com\", \"path_config\": \"generic\", \"recipe\": \"chromium\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"6ff787db0ddad97d7e6401692ba538fbab3e9010\", \"swarm_hashes_refs/heads/master(at){#823576}_without_patch\": {\"absl_hardening_tests\": \"0979e211dff75b2bedbff36038687ed6c868a44f\", \"accessibility_unittests\": \"7ecac849fa0b29c87a20c0b374e473207c29756d\", \"angle_unittests\": \"22fb5a6082087053eb0ca60198248ae4c6692800\", \"app_shell_unittests\": \"245690a3129d37fecb79d3ce7f07e68a44b471c2\", \"aura_unittests\": \"57aa0f65a903c024852f35d304b4abddc62cfaf2\", \"base_unittests\": \"f9cfcdef688472ed488dc4751decf0456bcdb22f\", \"base_util_unittests\": \"34d8cb3b0907c95c3ec16fa47e471275f38ec7eb\", \"blink_common_unittests\": \"7020ec458fbed19b95584f9e2faeb437f7fc7881\", \"blink_fuzzer_unittests\": \"cbace3d87291b29d4dc6194eecfd6087876a1f6b\", \"blink_heap_unittests\": \"910932f29b932d4443465c840a220b54386f60c4\", \"blink_platform_unittests\": \"a4b1c931b7704bd22df668dcb14fa1880b1d7545\", \"blink_unittests\": \"7d5668e5c8d0765169b21118da016983b3f6ce25\", \"boringssl_crypto_tests\": \"4188a0deabd2561ec21e35ba72ce5417158ffeea\", \"boringssl_ssl_tests\": \"d220d7e48d053546c8098a82a6c677e97ebd13f9\", \"browser_switcher_bho_unittests\": \"204b9791280c4d4914a6ac8786aef4226cef7937\", \"browser_tests\": \"3c9f7e2fbf06f403616bd41e8c3462368b6da246\", \"capture_unittests\": \"f12c02bdabfa62d9a13a14876b1b4104675ac736\", \"cast_unittests\": \"95e921b22195cfff2ee43dcf6e5351044a99738e\", \"cc_unittests\": \"340357e7b1fbc2f5fb7a20d8edf5282cbf669d1a\", \"chrome_app_unittests\": \"12fea1accdfe2c97657d410268809effca407b89\", \"chrome_cleaner_unittests\": \"117752adf0943d7bcd8c7ddeb88e0add0839d216\", \"chrome_elf_unittests\": \"bd5260d70336576a306a36de60db7d788dd897f9\", \"chromedriver_unittests\": \"d2ac8bbc39ac1a459a237141ae14612c7e1e9430\", \"components_browsertests\": \"edefa98c52b91f111496a61ad18ff0b4af9be718\", \"components_unittests\": \"e823fe514371701c156f98d89f3831ffdaaa932f\", \"compositor_unittests\": \"f4bcee0810517f0fb3ca7d5e56ad84e1af9612af\", \"content_browsertests\": \"6247752f574542c93483877331164883ec84fd23\", \"content_unittests\": \"b9e4200f91570e15589ff9ba079bcc70e5f933ee\", \"courgette_unittests\": \"2eb97ce94f62c69ec62970508b0eab41e71fb5d0\", \"crashpad_tests\": \"ac1f05ace3e6cead49d9efeaa7a1702594e39a57\", \"cronet_tests\": \"4b2608aea86aad40eb08dc7df2d47636dc8eed10\", \"cronet_unittests\": \"9523761fa6248a9af809b3767c9d37a1b5f3d501\", \"crypto_unittests\": \"93c3ecca9a65545b4986253cdde82af897ed6998\", \"delayloads_unittests\": \"0dca2d504508347c584073992c380b04ad16993c\", \"device_unittests\": \"bc8ed57c9ad2180741a639265824f7dc52614123\", \"display_unittests\": \"a0a2468b839c9926f975082f3b49a30f0113edb4\", \"elevation_service_unittests\": \"f8b28c9043c0d07fd589a98f4e3d942db5fdd81b\", \"events_unittests\": \"78d9b6012a297c4d13030885d9d77150adbcfa57\", \"extensions_browsertests\": \"35e5bee4ef3619c4168e4b683a47ebbd0b531a67\", \"extensions_unittests\": \"70dfcc04cd9a195e454892fbeb6a6e288c608cd4\", \"filesystem_service_unittests\": \"03e8c3596ba43f3fa7dec051cf1514262b3ca47a\", \"gcm_unit_tests\": \"e265573a77f570a41cc38833142a19fb9d906aa0\", \"gcp_unittests\": \"2dfcbe35571444197e58e807b46022c72d7bba97\", \"gfx_unittests\": \"5518de3b0e4c7be15c15875190955d89b2cd4583\", \"gin_unittests\": \"19608817b59d0b4a6c13c3f8cc53ea82108db6f1\", \"google_apis_unittests\": \"c96d2f76b16e587b1b796f05cbf05648f6ac9038\", \"gpu_unittests\": \"00a343520aacb2de41e8bbd89ad7a410cc4b9574\", \"gwp_asan_unittests\": \"0b5a3f75e6c689ab3cf054fb93b981bf7885d739\", \"headless_browsertests\": \"f23fbc3fca8565695d01ce2ffc37a78531d5f2f2\", \"headless_unittests\": \"5e99741fe763659d9d36697f5d203eb188103a3b\", \"install_static_unittests\": \"29ec5a806c693e9d6d8738a84f7bd9d3100752c1\", \"installer_util_unittests\": \"575474b659addf741a73a09e3005648fe3e690af\", \"interactive_ui_tests\": \"526bcf34546230d7aabe7f0090461d0d9a031e03\", \"ipc_tests\": \"29d91483963d0c303bc27a99fdf4bf5953aafca6\", \"jingle_unittests\": \"500eb85a38dce125bc8e869630ab6fe7db457464\", \"latency_unittests\": \"629e68d00c522714365a324c086dd855110e1afc\", \"libjingle_xmpp_unittests\": \"e85d267b1fc484aca4da3e4bdf1d755d46e9893f\", \"liburlpattern_unittests\": \"fbe139c3e4d0a82a33b09f020496b9bfbf60802a\", \"media_blink_unittests\": \"a8661d4b2f2074f4f5db183cd5c05a1ac83c0ccb\", \"media_unittests\": \"8e5643394d09f283f3e8835e9937420cbffe9deb\", \"message_center_unittests\": \"442fdf8da61fe8c968c3b8f91ca2d8b6f747a131\", \"midi_unittests\": \"40d8deb0a61f84d9fe6e2d5444d6f09981cc33a1\", \"mojo_core_unittests\": \"3051f29f90dd6d75c2e1cc9ca7638f4eb89582bd\", \"mojo_unittests\": \"5178786adf1a541429e5d91ea4d3653e16b14380\", \"nacl_loader_unittests\": \"ba97448672430ae69656efed078f3424a6cf6d36\", \"native_theme_unittests\": \"6c5f3accd1fc37e04acef15784256502c284ecbe\", \"net_unittests\": \"dd0297e65da5c3c0ceb4d6bedc73fa39fcd575d1\", \"notification_helper_unittests\": \"77c75e968ad5c10673e0b8d6029fb387a6e4c0a8\", \"pdf_unittests\": \"00da1e85273842494508d037bc1a52b9946c8b59\", \"perfetto_unittests\": \"1824cd7a0b718afc2243dc065ad489f4e0077b7a\", \"ppapi_unittests\": \"3ba3f4ef3d008f7b61bd73f1be6beacbb4542c3c\", \"printing_unittests\": \"53ffcf15b4b5a06144b38cf06e9033ee7bfe2ece\", \"remoting_unittests\": \"add9aa738ffd815aa1a57973bf72d3741fec9d46\", \"sbox_integration_tests\": \"a16fb5cc19cd926d95ace79c998e51876515c5f9\", \"sbox_unittests\": \"a21b4c8cb1c0eeb88ec2bbb1554908253865a84e\", \"sbox_validation_tests\": \"03888581661332c067847984d48458b77c6c5af5\", \"service_manager_unittests\": \"991c51391d630836dd45591dc10a8d2400f82ec1\", \"services_unittests\": \"23d28609890ca3eb4c352f014b97ec16c8252a2f\", \"setup_unittests\": \"b86f0df6ee55dcb66ff029ec27753130299499a4\", \"shell_dialogs_unittests\": \"f59dbc86fbc520af8803e6d82c21236c4fdbb831\", \"skia_unittests\": \"b2df5b2c7147f6751efea891f97758bb5ef46ccf\", \"snapshot_unittests\": \"1c2a40d385b667109fc6f77c84fc3631c3ec5d80\", \"sql_unittests\": \"945443c20014d036b56e82ee39b247f562a0ed03\", \"storage_unittests\": \"8645baacd95323ce5a4c6b2b92d8db47f8f38504\", \"sync_integration_tests\": \"ec9c537bb61bdaa34cac7797aade41a9f37bd6b7\", \"traffic_annotation_auditor_unittests\": \"dac6d230774a3192ffc9ce952358472e7fdee2d7\", \"ui_base_unittests\": \"a91f30966969ed515a2445bc1be475409834698a\", \"ui_touch_selection_unittests\": \"d1ea14c8208347967ba17c3440890a84f7cb186a\", \"unit_tests\": \"1ff775e35ad4aade6172b55fe7fbb138a29f7264\", \"updater_tests\": \"235e64d764a2237ec89f248a3ea69437242bbd39\", \"url_unittests\": \"3beb4107f711afda3a475270bc917040754972e3\", \"views_unittests\": \"2fe35b01095374d9a00a27386dd8c5882495a254\", \"viz_unittests\": \"a45394abf6a6335bb12f13edfecf268022ef7786\", \"vr_common_unittests\": \"c2d1461f6ec98ce4e1b51d880ba7986c0cf56114\", \"vr_pixeltests\": \"6cecaebe0cf9a7d92a86cbc760b0ef802c96fcd1\", \"weblayer_browsertests\": \"31e75139bac33f4425b89175a15101a8dcaeb74f\", \"weblayer_unittests\": \"393b6261606d6265e90b39a8a8630275a5c51695\", \"wm_unittests\": \"492ab85d3652028e92ed9d86bc22144b2a016890\", \"wtf_unittests\": \"f31f96d74bc22b785d78c4ae8d91760e40032d44\", \"zlib_unittests\": \"e07821a9877f2b741464b9a344166e0e0b02ec24\", \"zucchini_unittests\": \"34a4a72b7a8fe827830b57d7cdb6715ee8240b02\"}}, \"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-5-vrjs\"], \"image\": [\"chrome-win10-1703-20092300-22ba5320847\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5614-d862eaa\"], \"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": "1604423785385492",
"status": "COMPLETED",
"status_changed_ts": "1604427624002385",
"tags": [
"build_address:luci.chromium.ci/win-asan/15438",
"builder:win-asan",
"buildset:commit/git/6ff787db0ddad97d7e6401692ba538fbab3e9010",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/6ff787db0ddad97d7e6401692ba538fbab3e9010",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9030063506312682272",
"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/8864633444323356704/+/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:4fa7ed9997200d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1604427624007213",
"url": "https://ci.chromium.org/b/8864633444323356704",
"utcnow_ts": "1604427624612201"
}
}
[18:20:24.620] Invocation finished in 1h4m3.900572685s with status SUCCEEDED