[16:18:28.711] New invocation is queued and will start shortly
[16:18:29.874] Starting the invocation (attempt 1)
[16:18:29.912] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:18:29.912] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:18:29.912] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9023453568446505600",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9023453568446505600",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"gitiles_ref:refs/heads/master"
]
}
[16:18:30.730] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1610727510038097",
"id": "8858023506392881376",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1610727510493346",
"tags": [
"build_address:luci.chromium.ci/win-asan/16841",
"builder:win-asan",
"buildset:commit/git/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023453568446505600",
"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/8858023506392881376/+/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": "1610727510493891",
"url": "https://ci.chromium.org/b/8858023506392881376",
"utcnow_ts": "1610727510720908"
}
}
[16:18:30.730] Task URL: https://ci.chromium.org/b/8858023506392881376
[16:18:30.730] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:2:0) after 1m0s
[16:18:34.303] Received PubSub notification, asking Buildbucket for the build status
[16:18:34.517] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:30.854] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:2:0)
[16:19:30.854] Timer tick, asking Buildbucket for the build status
[16:19:30.939] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:30.939] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:4:0) after 1m0s
[16:20:31.067] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:4:0)
[16:20:31.067] Timer tick, asking Buildbucket for the build status
[16:20:31.185] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:31.185] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:5:0) after 1m0s
[16:21:31.274] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:5:0)
[16:21:31.274] Timer tick, asking Buildbucket for the build status
[16:21:32.021] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:32.021] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:6:0) after 1m0s
[16:22:32.015] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:6:0)
[16:22:32.015] Timer tick, asking Buildbucket for the build status
[16:22:32.192] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:32.193] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:7:0) after 1m0s
[16:23:32.256] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:7:0)
[16:23:32.256] Timer tick, asking Buildbucket for the build status
[16:23:32.636] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:32.636] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:8:0) after 1m0s
[16:24:32.657] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:8:0)
[16:24:32.657] Timer tick, asking Buildbucket for the build status
[16:24:32.913] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:32.913] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:9:0) after 1m0s
[16:25:32.936] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:9:0)
[16:25:32.936] Timer tick, asking Buildbucket for the build status
[16:25:32.999] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:32.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:10:0) after 1m0s
[16:26:33.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:10:0)
[16:26:33.003] Timer tick, asking Buildbucket for the build status
[16:26:33.226] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:33.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:11:0) after 1m0s
[16:27:33.261] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:11:0)
[16:27:33.261] Timer tick, asking Buildbucket for the build status
[16:27:33.541] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:33.541] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:12:0) after 1m0s
[16:28:33.556] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:12:0)
[16:28:33.556] Timer tick, asking Buildbucket for the build status
[16:28:33.651] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:33.651] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:13:0) after 1m0s
[16:29:33.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:13:0)
[16:29:33.740] Timer tick, asking Buildbucket for the build status
[16:29:33.802] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:33.802] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:14:0) after 1m0s
[16:30:33.955] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:14:0)
[16:30:33.955] Timer tick, asking Buildbucket for the build status
[16:30:34.042] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:34.042] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:15:0) after 1m0s
[16:31:34.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:15:0)
[16:31:34.115] Timer tick, asking Buildbucket for the build status
[16:31:34.173] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:34.173] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:16:0) after 1m0s
[16:32:34.355] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:16:0)
[16:32:34.355] Timer tick, asking Buildbucket for the build status
[16:32:34.548] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:34.548] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:17:0) after 1m0s
[16:33:35.681] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:17:0)
[16:33:35.681] Timer tick, asking Buildbucket for the build status
[16:33:36.111] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:36.111] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:18:0) after 1m0s
[16:34:36.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:18:0)
[16:34:36.201] Timer tick, asking Buildbucket for the build status
[16:34:36.436] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:36.436] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:19:0) after 1m0s
[16:35:36.483] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:19:0)
[16:35:36.483] Timer tick, asking Buildbucket for the build status
[16:35:36.542] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:36.542] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:20:0) after 1m0s
[16:36:36.566] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:20:0)
[16:36:36.566] Timer tick, asking Buildbucket for the build status
[16:36:36.682] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:36.682] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:21:0) after 1m0s
[16:37:39.806] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:21:0)
[16:37:39.806] Timer tick, asking Buildbucket for the build status
[16:37:40.167] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:40.167] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:22:0) after 1m0s
[16:38:40.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:22:0)
[16:38:40.579] Timer tick, asking Buildbucket for the build status
[16:38:40.697] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:38:40.697] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:23:0) after 1m0s
[16:39:40.896] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:23:0)
[16:39:40.896] Timer tick, asking Buildbucket for the build status
[16:39:41.071] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:41.071] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:24:0) after 1m0s
[16:40:41.125] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:24:0)
[16:40:41.125] Timer tick, asking Buildbucket for the build status
[16:40:41.440] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:41.440] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:25:0) after 1m0s
[16:41:41.532] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:25:0)
[16:41:41.532] Timer tick, asking Buildbucket for the build status
[16:41:41.907] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:41.907] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:26:0) after 1m0s
[16:42:42.111] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:26:0)
[16:42:42.111] Timer tick, asking Buildbucket for the build status
[16:42:42.254] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:42:42.254] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:27:0) after 1m0s
[16:43:42.294] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:27:0)
[16:43:42.294] Timer tick, asking Buildbucket for the build status
[16:43:42.444] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:43:42.444] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:28:0) after 1m0s
[16:44:42.545] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:28:0)
[16:44:42.545] Timer tick, asking Buildbucket for the build status
[16:44:42.694] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:44:42.694] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:29:0) after 1m0s
[16:45:42.780] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:29:0)
[16:45:42.780] Timer tick, asking Buildbucket for the build status
[16:45:42.930] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:45:42.930] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:30:0) after 1m0s
[16:46:42.962] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:30:0)
[16:46:42.962] Timer tick, asking Buildbucket for the build status
[16:46:43.197] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:46:43.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:31:0) after 1m0s
[16:47:43.265] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:31:0)
[16:47:43.265] Timer tick, asking Buildbucket for the build status
[16:47:43.447] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:47:43.447] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:32:0) after 1m0s
[16:48:43.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:32:0)
[16:48:43.469] Timer tick, asking Buildbucket for the build status
[16:48:43.996] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:48:43.996] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:33:0) after 1m0s
[16:49:44.065] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:33:0)
[16:49:44.065] Timer tick, asking Buildbucket for the build status
[16:49:44.411] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:49:44.411] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:34:0) after 1m0s
[16:50:44.533] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:34:0)
[16:50:44.533] Timer tick, asking Buildbucket for the build status
[16:50:44.736] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:50:44.736] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:35:0) after 1m0s
[16:51:44.897] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:35:0)
[16:51:44.897] Timer tick, asking Buildbucket for the build status
[16:51:44.972] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:51:44.972] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:36:0) after 1m0s
[16:52:45.145] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:36:0)
[16:52:45.145] Timer tick, asking Buildbucket for the build status
[16:52:45.359] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:52:45.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:37:0) after 1m0s
[16:53:45.621] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:37:0)
[16:53:45.621] Timer tick, asking Buildbucket for the build status
[16:53:45.727] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:53:45.727] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:38:0) after 1m0s
[16:54:45.956] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:38:0)
[16:54:45.956] Timer tick, asking Buildbucket for the build status
[16:54:46.043] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:54:46.043] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:39:0) after 1m0s
[16:55:46.269] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:39:0)
[16:55:46.269] Timer tick, asking Buildbucket for the build status
[16:55:46.418] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:55:46.418] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:40:0) after 1m0s
[16:56:46.592] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:40:0)
[16:56:46.592] Timer tick, asking Buildbucket for the build status
[16:56:46.804] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:56:46.804] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:41:0) after 1m0s
[16:57:46.833] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:41:0)
[16:57:46.833] Timer tick, asking Buildbucket for the build status
[16:57:47.394] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:57:47.394] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:42:0) after 1m0s
[16:58:47.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:42:0)
[16:58:47.591] Timer tick, asking Buildbucket for the build status
[16:58:47.999] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:58:47.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:43:0) after 1m0s
[16:59:48.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:43:0)
[16:59:48.104] Timer tick, asking Buildbucket for the build status
[16:59:48.227] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:59:48.227] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:44:0) after 1m0s
[17:00:48.467] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:44:0)
[17:00:48.467] Timer tick, asking Buildbucket for the build status
[17:00:48.549] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:00:48.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:45:0) after 1m0s
[17:01:48.644] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:45:0)
[17:01:48.644] Timer tick, asking Buildbucket for the build status
[17:01:48.786] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:01:48.786] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:46:0) after 1m0s
[17:02:48.924] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:46:0)
[17:02:48.924] Timer tick, asking Buildbucket for the build status
[17:02:49.022] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:02:49.022] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:47:0) after 1m0s
[17:03:49.080] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:47:0)
[17:03:49.080] Timer tick, asking Buildbucket for the build status
[17:03:49.321] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:03:49.321] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:48:0) after 1m0s
[17:04:49.348] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:48:0)
[17:04:49.348] Timer tick, asking Buildbucket for the build status
[17:04:49.499] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:04:49.499] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:49:0) after 1m0s
[17:05:49.571] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:49:0)
[17:05:49.571] Timer tick, asking Buildbucket for the build status
[17:05:49.980] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:49.980] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:50:0) after 1m0s
[17:06:50.003] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:50:0)
[17:06:50.003] Timer tick, asking Buildbucket for the build status
[17:06:50.115] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:50.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:51:0) after 1m0s
[17:07:50.241] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:51:0)
[17:07:50.241] Timer tick, asking Buildbucket for the build status
[17:07:50.549] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:50.549] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:52:0) after 1m0s
[17:08:50.579] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:52:0)
[17:08:50.579] Timer tick, asking Buildbucket for the build status
[17:08:50.724] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:50.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:53:0) after 1m0s
[17:09:50.831] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:53:0)
[17:09:50.831] Timer tick, asking Buildbucket for the build status
[17:09:50.937] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:50.937] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:54:0) after 1m0s
[17:10:51.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:54:0)
[17:10:51.042] Timer tick, asking Buildbucket for the build status
[17:10:51.454] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:51.455] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:55:0) after 1m0s
[17:11:51.599] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:55:0)
[17:11:51.599] Timer tick, asking Buildbucket for the build status
[17:11:51.758] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:51.758] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:56:0) after 1m0s
[17:12:51.811] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:56:0)
[17:12:51.811] Timer tick, asking Buildbucket for the build status
[17:12:51.935] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:51.935] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:57:0) after 1m0s
[17:13:52.042] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:57:0)
[17:13:52.042] Timer tick, asking Buildbucket for the build status
[17:13:52.214] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:52.214] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:58:0) after 1m0s
[17:14:52.293] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:58:0)
[17:14:52.293] Timer tick, asking Buildbucket for the build status
[17:14:52.611] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:52.611] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:59:0) after 1m0s
[17:15:52.641] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:59:0)
[17:15:52.641] Timer tick, asking Buildbucket for the build status
[17:15:52.722] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:52.723] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:60:0) after 1m0s
[17:16:52.750] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:60:0)
[17:16:52.751] Timer tick, asking Buildbucket for the build status
[17:16:52.822] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:52.822] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:61:0) after 1m0s
[17:17:52.875] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:61:0)
[17:17:52.875] Timer tick, asking Buildbucket for the build status
[17:17:53.144] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:53.144] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:62:0) after 1m0s
[17:18:53.217] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:62:0)
[17:18:53.217] Timer tick, asking Buildbucket for the build status
[17:18:53.342] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:53.342] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:63:0) after 1m0s
[17:19:53.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:63:0)
[17:19:53.375] Timer tick, asking Buildbucket for the build status
[17:19:53.448] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:53.448] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:64:0) after 1m0s
[17:20:53.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:64:0)
[17:20:53.507] Timer tick, asking Buildbucket for the build status
[17:20:53.605] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:53.605] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:65:0) after 1m0s
[17:21:53.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:65:0)
[17:21:53.656] Timer tick, asking Buildbucket for the build status
[17:21:53.724] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:53.724] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:66:0) after 1m0s
[17:22:54.026] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:66:0)
[17:22:54.026] Timer tick, asking Buildbucket for the build status
[17:22:54.272] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:54.272] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:67:0) after 1m0s
[17:23:54.648] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:67:0)
[17:23:54.648] Timer tick, asking Buildbucket for the build status
[17:23:54.783] Build 8858023506392881376: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:54.783] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9023453568446505600:68:0) after 1m0s
[17:24:16.519] Received PubSub notification, asking Buildbucket for the build status
[17:24:16.763] Build 8858023506392881376: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[17:24:16.763] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1610731455273957",
"created_by": "project:chromium",
"created_ts": "1610727510038097",
"id": "8858023506392881376",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\"}}",
"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\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\", \"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-nb2x\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 16841, \"clang_revision\": \"llvmorg-12-init-16296-g5e476061-1\", \"got_angle_revision\": \"5678b56f5e2129615193034cd90bb6a0ef3c70d3\", \"got_dawn_revision\": \"5a6fda1a6fcc99553bab1411e2159cf962b6a36d\", \"got_nacl_revision\": \"cb31feffa500401c95c6c44d88b7c358236bca36\", \"got_revision\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\", \"got_revision_cp\": \"refs/heads/master@{#844091}\", \"got_swarming_client_revision\": \"1a072711d4388c62e02480fabc26c68c24494be9\", \"got_swiftshader_revision\": \"aedd133cd85f40f92c304776d240a8a3503d1d71\", \"got_v8_revision\": \"16b9bbbd581c25391981aa03180b76aa60463a3e\", \"got_v8_revision_cp\": \"refs/heads/8.9.255@{#1}\", \"got_webrtc_revision\": \"7acc2d9fe3a6e3c4d8881d2bdfc9b8968a724cd5\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#32986}\", \"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\": \"771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596\", \"swarm_hashes_refs/heads/master(at){#844091}_without_patch\": {\"absl_hardening_tests\": \"e80e92a86c11a52a4cf3f675de43aec506c9e3ea\", \"accessibility_unittests\": \"a8536340b02f3f23ed80a82666b517cec42d8233\", \"angle_unittests\": \"633b60da537bc4338e18cac8bd08a9aead46f663\", \"app_shell_unittests\": \"f602d9a22489a287aee1ed431dd7723dca496491\", \"aura_unittests\": \"067a87ba197276b3239d5d0a78313126a93ff2c0\", \"base_unittests\": \"13cc607814ad1a34dd543d0b0d3c68e469ec0f7c\", \"base_util_unittests\": \"531aa59189cf3c84d9310590aa31901cf8fab6be\", \"blink_common_unittests\": \"e4918a64b10e86003b20d4c97ac0954e90403047\", \"blink_fuzzer_unittests\": \"e7c82d42a53087bf07f10bda375c0a06b57bd0c0\", \"blink_heap_unittests\": \"ae47a4ce2d0b743c5992a0872e9db36457a6828c\", \"blink_platform_unittests\": \"1827da3c3d399e3415d1a02a1f7d5ddb4e06a6e8\", \"blink_unittests\": \"cf4670e0183dd1e05a17cd747e88e26c98c12b2e\", \"boringssl_crypto_tests\": \"b995521b6f19b3b11577bfa9e47f07c1b5caaf53\", \"boringssl_ssl_tests\": \"cdd021a97166d08e7f1c5650219ec2c0451fd25b\", \"browser_switcher_bho_unittests\": \"d59b8f24bd6d2d6214b7eb96648da77ae91641d1\", \"browser_tests\": \"d07a62c6bdadab3d7ed740a2a56e7ef1884f45b5\", \"capture_unittests\": \"25719c7a6fb7a23a98849493bb161df42768149a\", \"cast_unittests\": \"99a1272bfaeec54514ff01f820ab927ed8ec46ad\", \"cc_unittests\": \"72261b989a1c3ba5734a8cc83e89a9d40280a4eb\", \"chrome_app_unittests\": \"4c03f27d767fb72d83fa65d81f4d531a98f487c7\", \"chrome_cleaner_unittests\": \"60c80ee1fd45f9cb72722ea186978ff6a80d8514\", \"chrome_elf_unittests\": \"e809cf333e42d9f6ed6feb67d9f28f4afc6a04fa\", \"chromedriver_unittests\": \"7f447e08b5d58e71fa7f44026ee1d2129eeaebbc\", \"components_browsertests\": \"d49cfa90044f4e9edc758cdcc96bf0e6448b0bea\", \"components_unittests\": \"bd42d4d754d0337bfcb69522200e30edd28cd18d\", \"compositor_unittests\": \"8c3b41feb145ba676974aad55f67e064df3d30be\", \"content_browsertests\": \"2d9556bb9623c099795881e8dde7794e6288a747\", \"content_unittests\": \"4f6271dfa7ca4c07ecc214493c24e9c1bd0f184e\", \"courgette_unittests\": \"b8b6c92c7b06b4d764c063717d0a5955a98de60b\", \"crashpad_tests\": \"06220aa4edecc39e23a185493813b2c21b5b94fd\", \"cronet_tests\": \"002b430a417794ffae98e47832e4355ac354d4ce\", \"cronet_unittests\": \"043b0f0c7f37a21e12e99c73c280756808ad6079\", \"crypto_unittests\": \"e2b0d161341807935eac59ec866a6fcea383d0ed\", \"delayloads_unittests\": \"fb93a94f06dd052722d437147c9143c6856d3774\", \"device_unittests\": \"02b265e36c379f14026c05764c0d1be676a49297\", \"display_unittests\": \"6777e0b6a92d147801d90e61d37fd44b1096f88b\", \"elevation_service_unittests\": \"460635cf24fc1957aa624412cd12d926ead80431\", \"events_unittests\": \"0eb93cb71ff2717c1d39d5a00846220115f29173\", \"extensions_browsertests\": \"266a9154305687bed9555b4836e079386913ee61\", \"extensions_unittests\": \"506078ac27feb208af3eda9dda8aaa13bceb8b20\", \"filesystem_service_unittests\": \"1a0a90ec0e5e56ae3e1106d44d642ed86eb571b7\", \"gcm_unit_tests\": \"a074ca76f8de2582d757343244ec1a6c6540711b\", \"gcp_unittests\": \"9a4458268b9662e0211febee494091318111b985\", \"gfx_unittests\": \"8d9fc8d1f1f70d9f89d102c73938e38c4af2cd1f\", \"gin_unittests\": \"a36ae523b97dbe94b0fdea36d7b2f8f8f921d8c6\", \"google_apis_unittests\": \"82148783d5e4e1894a7419e531b67bf27ff41dc9\", \"gpu_unittests\": \"751ba6517619397149600a3cf737ded54c503790\", \"gwp_asan_unittests\": \"6d1b502b740bf4628309e3c928dcff3d0295e956\", \"headless_browsertests\": \"c6c59022e9f9d0b34b9f184db1b9bdaa37a0a298\", \"headless_unittests\": \"ae544b98f1269836af57a8ee6b3c84462e5905c1\", \"install_static_unittests\": \"d49b57e36d9f580b2207b3a09bab7dc64c1c508a\", \"installer_util_unittests\": \"119e0ca5aad70f5402d993b0c05203a4f317d06b\", \"interactive_ui_tests\": \"e2a446f7c32a74af2dea51f7437c8e5662836475\", \"ipc_tests\": \"04b29ea2dcac58398dc589b81059bb615997fce1\", \"jingle_unittests\": \"36fd69b770404dcc6a40de0af27864991a14cb0e\", \"latency_unittests\": \"a52d62052f352647292a56f64c43126e68a233f8\", \"libjingle_xmpp_unittests\": \"24ec27fab270c2c07d1aae3a917d1d4592bd194f\", \"liburlpattern_unittests\": \"495c955f01e6fdcdefb5d15c755815bb921457ca\", \"media_blink_unittests\": \"7996c33148db556c0a2fe830adca400b314b243e\", \"media_unittests\": \"58be84320545b833f6ad29f6108449830b71aad5\", \"message_center_unittests\": \"b5e82d9e04fc6fc4827272d80b008b6e95304d14\", \"midi_unittests\": \"63ef9c56554fdcd74f5124fb04fc6dcc32676df1\", \"mojo_core_unittests\": \"f6710360f13b359ca507b66bfd8b784e8620216d\", \"mojo_unittests\": \"0d93ad86fb0ea2812201b811313ad11aa5ab3245\", \"nacl_loader_unittests\": \"b7cd623c6b1031c5c733bfbf4d54172e76228a5a\", \"native_theme_unittests\": \"d027858932147252f7713e50b6dbac722a899b95\", \"net_unittests\": \"6bb81a241cff53a973a4a50e8371210c2e2a2c6e\", \"notification_helper_unittests\": \"ebe4ba8b186145bd9a1891232356cd711bea2d6a\", \"pdf_unittests\": \"45af0185e2c7b85c00ec0fdd490ddb85e7cc70da\", \"perfetto_unittests\": \"624ce3abbe2f373f9756a323bfb69ca848cc380c\", \"ppapi_unittests\": \"1d1e718c0826a073db69ce40e64cadac340c2375\", \"printing_unittests\": \"8979803b674817a531c5ae8d3607524cbf009a8a\", \"remoting_unittests\": \"0d1a124011a76bfd21cf2c4f9f14bc706014ddaa\", \"sbox_integration_tests\": \"b97a238235ba2e9b70ff904ee1cfcd5b6dca02dc\", \"sbox_unittests\": \"e6384c634732dc697d9968a7896a260ce5f4ba80\", \"sbox_validation_tests\": \"a445ad3b3d19612cdc2cf663ec2fb4c9a5646b8d\", \"service_manager_unittests\": \"de521c64743537dbee403d93eb92542657069db1\", \"services_unittests\": \"861c4bbaa1942ebabc05f70b3f46ee8c5d7a8e0f\", \"setup_unittests\": \"d322123ec3a521892de2c330838d7b713c690566\", \"shell_dialogs_unittests\": \"713166368cde9af6f63e3ecc53224191f3e4e01c\", \"skia_unittests\": \"819b1ea362c95a27279001f2bee92b9bd1eb2aee\", \"snapshot_unittests\": \"1834d4e5025f12edc1ad4a6360c15bdbcda4adf0\", \"sql_unittests\": \"e5adab62a9ed29fb63800cf9acc67796c9ed1425\", \"storage_unittests\": \"c2de7f025cb823dac151dcf45fd85c3be56d050d\", \"sync_integration_tests\": \"05c69b795526f4bf118fdd52c3095b77cac4b800\", \"traffic_annotation_auditor_unittests\": \"5ca5b0dfb6e577335278e6a58d35ac57539b9360\", \"ui_base_unittests\": \"85c799393cb76da6377a5a00f8fdfe61d36e8ede\", \"ui_touch_selection_unittests\": \"cecd6dd223efc860cb22d36109e47e5d30138fe0\", \"unit_tests\": \"ea0b0fb7c0e21de2dcaeea87e6f6f6f68372f888\", \"updater_tests\": \"5fa23feef6dcc4763b61baaa65b038e4eaa29c20\", \"url_unittests\": \"ca5c3c0079d3547037355463520ce302894f4548\", \"views_unittests\": \"9d3b158db4a6c61ef3d140a27cb972b9f457dfdd\", \"viz_unittests\": \"2ca1fcc2d4bd6ee4f73baf548dd9acd84e3d0b96\", \"vr_common_unittests\": \"c4c948ce7e8a9ead83f1f55361204870ed41c7bd\", \"vr_pixeltests\": \"e3744cdb0bc81c7767027c57788442b04c8216d0\", \"weblayer_browsertests\": \"d514784c36e2bbb2a9608f49f83d6354bb59864b\", \"weblayer_unittests\": \"3b456158e162aed0da34efdf5584cf70c7f57d99\", \"wm_unittests\": \"2ee0f6f14095cd0df17278e0e7bea140a51956b7\", \"wtf_unittests\": \"c0d420214a88b5ca77228fdc31d372cbbec337d1\", \"zlib_unittests\": \"2bac7ff559dcb01788a3ee11922da5c6ce43ca1e\", \"zucchini_unittests\": \"e4aae04e68d3e116a1918ef7332ae6230c43894f\"}}, \"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-nb2x\"], \"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\": [\"5666-679c9bd\"], \"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": "1610727512900967",
"status": "COMPLETED",
"status_changed_ts": "1610731455751217",
"tags": [
"build_address:luci.chromium.ci/win-asan/16841",
"builder:win-asan",
"buildset:commit/git/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/771f9057e52e7f59c1bdd6ba0ebd5ddbd9250596",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9023453568446505600",
"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/8858023506392881376/+/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:511fa8dc77f12d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1610731455757003",
"url": "https://ci.chromium.org/b/8858023506392881376",
"utcnow_ts": "1610731456752234"
}
}
[17:24:16.763] Invocation finished in 1h5m48.067269255s with status SUCCEEDED