[19:04:24.799] New invocation is queued and will start shortly
[19:04:25.879] Starting the invocation (attempt 1)
[19:04:25.912] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:04:25.912] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:04:25.912] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021449995471993856",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"7f594365046a78c5a6032e4e07d4d3def3bbcc50\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021449995471993856",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/7f594365046a78c5a6032e4e07d4d3def3bbcc50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f594365046a78c5a6032e4e07d4d3def3bbcc50",
"gitiles_ref:refs/heads/master"
]
}
[19:04:27.613] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612638266104707",
"id": "8856019933440277600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f594365046a78c5a6032e4e07d4d3def3bbcc50\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612638267391779",
"tags": [
"build_address:luci.chromium.ci/win-asan/17202",
"builder:win-asan",
"buildset:commit/git/7f594365046a78c5a6032e4e07d4d3def3bbcc50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f594365046a78c5a6032e4e07d4d3def3bbcc50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021449995471993856",
"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/8856019933440277600/+/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": "1612638267391913",
"url": "https://ci.chromium.org/b/8856019933440277600",
"utcnow_ts": "1612638267603927"
}
}
[19:04:27.613] Task URL: https://ci.chromium.org/b/8856019933440277600
[19:04:27.613] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:2:0) after 1m0s
[19:04:32.908] Received PubSub notification, asking Buildbucket for the build status
[19:04:33.082] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:27.647] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:2:0)
[19:05:27.647] Timer tick, asking Buildbucket for the build status
[19:05:28.057] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:05:28.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:4:0) after 1m0s
[19:06:28.112] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:4:0)
[19:06:28.112] Timer tick, asking Buildbucket for the build status
[19:06:28.669] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:06:28.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:5:0) after 1m0s
[19:07:28.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:5:0)
[19:07:28.719] Timer tick, asking Buildbucket for the build status
[19:07:28.855] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:07:28.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:6:0) after 1m0s
[19:08:28.881] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:6:0)
[19:08:28.881] Timer tick, asking Buildbucket for the build status
[19:08:29.013] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:08:29.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:7:0) after 1m0s
[19:09:29.077] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:7:0)
[19:09:29.077] Timer tick, asking Buildbucket for the build status
[19:09:29.685] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:09:29.685] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:8:0) after 1m0s
[19:10:29.719] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:8:0)
[19:10:29.719] Timer tick, asking Buildbucket for the build status
[19:10:29.813] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:10:29.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:9:0) after 1m0s
[19:11:29.842] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:9:0)
[19:11:29.842] Timer tick, asking Buildbucket for the build status
[19:11:30.705] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:11:30.705] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:10:0) after 1m0s
[19:12:30.724] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:10:0)
[19:12:30.724] Timer tick, asking Buildbucket for the build status
[19:12:30.827] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:12:30.827] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:11:0) after 1m0s
[19:13:30.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:11:0)
[19:13:30.841] Timer tick, asking Buildbucket for the build status
[19:13:30.917] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:13:30.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:12:0) after 1m0s
[19:14:30.981] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:12:0)
[19:14:30.982] Timer tick, asking Buildbucket for the build status
[19:14:32.299] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:14:32.299] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:13:0) after 1m0s
[19:15:32.332] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:13:0)
[19:15:32.332] Timer tick, asking Buildbucket for the build status
[19:15:32.438] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:15:32.438] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:14:0) after 1m0s
[19:16:32.475] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:14:0)
[19:16:32.476] Timer tick, asking Buildbucket for the build status
[19:16:32.719] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:16:32.719] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:15:0) after 1m0s
[19:17:32.739] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:15:0)
[19:17:32.739] Timer tick, asking Buildbucket for the build status
[19:17:33.115] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:17:33.115] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:16:0) after 1m0s
[19:18:33.170] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:16:0)
[19:18:33.171] Timer tick, asking Buildbucket for the build status
[19:18:33.226] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:18:33.226] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:17:0) after 1m0s
[19:19:33.315] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:17:0)
[19:19:33.315] Timer tick, asking Buildbucket for the build status
[19:19:33.433] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:19:33.433] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:18:0) after 1m0s
[19:20:33.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:18:0)
[19:20:33.449] Timer tick, asking Buildbucket for the build status
[19:20:33.523] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:20:33.523] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:19:0) after 1m0s
[19:21:33.560] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:19:0)
[19:21:33.560] Timer tick, asking Buildbucket for the build status
[19:21:33.893] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:21:33.893] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:20:0) after 1m0s
[19:22:33.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:20:0)
[19:22:33.946] Timer tick, asking Buildbucket for the build status
[19:22:34.190] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:22:34.190] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:21:0) after 1m0s
[19:23:34.246] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:21:0)
[19:23:34.246] Timer tick, asking Buildbucket for the build status
[19:23:34.401] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:23:34.401] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:22:0) after 1m0s
[19:24:34.634] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:22:0)
[19:24:34.634] Timer tick, asking Buildbucket for the build status
[19:24:34.755] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:24:34.755] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:23:0) after 1m0s
[19:25:34.879] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:23:0)
[19:25:34.879] Timer tick, asking Buildbucket for the build status
[19:25:35.285] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:25:35.285] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:24:0) after 1m0s
[19:26:35.412] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:24:0)
[19:26:35.412] Timer tick, asking Buildbucket for the build status
[19:26:35.796] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:26:35.796] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:25:0) after 1m0s
[19:27:35.840] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:25:0)
[19:27:35.840] Timer tick, asking Buildbucket for the build status
[19:27:36.123] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:27:36.123] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:26:0) after 1m0s
[19:28:36.216] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:26:0)
[19:28:36.216] Timer tick, asking Buildbucket for the build status
[19:28:36.356] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:28:36.357] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:27:0) after 1m0s
[19:29:36.464] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:27:0)
[19:29:36.464] Timer tick, asking Buildbucket for the build status
[19:29:36.793] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:29:36.793] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:28:0) after 1m0s
[19:30:36.841] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:28:0)
[19:30:36.841] Timer tick, asking Buildbucket for the build status
[19:30:37.020] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:30:37.020] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:29:0) after 1m0s
[19:31:37.038] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:29:0)
[19:31:37.038] Timer tick, asking Buildbucket for the build status
[19:31:37.351] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:31:37.351] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:30:0) after 1m0s
[19:32:37.323] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:30:0)
[19:32:37.323] Timer tick, asking Buildbucket for the build status
[19:32:37.838] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:32:37.838] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:31:0) after 1m0s
[19:33:37.867] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:31:0)
[19:33:37.867] Timer tick, asking Buildbucket for the build status
[19:33:38.026] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:33:38.026] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:32:0) after 1m0s
[19:34:38.115] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:32:0)
[19:34:38.115] Timer tick, asking Buildbucket for the build status
[19:34:38.917] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:34:38.917] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:33:0) after 1m0s
[19:35:38.968] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:33:0)
[19:35:38.968] Timer tick, asking Buildbucket for the build status
[19:35:39.329] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:35:39.329] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:34:0) after 1m0s
[19:36:39.375] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:34:0)
[19:36:39.375] Timer tick, asking Buildbucket for the build status
[19:36:39.506] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:36:39.506] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:35:0) after 1m0s
[19:37:39.661] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:35:0)
[19:37:39.661] Timer tick, asking Buildbucket for the build status
[19:37:39.835] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:37:39.835] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:36:0) after 1m0s
[19:38:39.860] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:36:0)
[19:38:39.860] Timer tick, asking Buildbucket for the build status
[19:38:39.922] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:38:39.922] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:37:0) after 1m0s
[19:39:39.939] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:37:0)
[19:39:39.939] Timer tick, asking Buildbucket for the build status
[19:39:40.060] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:39:40.060] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:38:0) after 1m0s
[19:40:40.127] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:38:0)
[19:40:40.127] Timer tick, asking Buildbucket for the build status
[19:40:40.223] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:40:40.223] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:39:0) after 1m0s
[19:41:40.280] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:39:0)
[19:41:40.280] Timer tick, asking Buildbucket for the build status
[19:41:40.359] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:41:40.359] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:40:0) after 1m0s
[19:42:40.378] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:40:0)
[19:42:40.378] Timer tick, asking Buildbucket for the build status
[19:42:40.446] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:42:40.446] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:41:0) after 1m0s
[19:43:40.479] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:41:0)
[19:43:40.479] Timer tick, asking Buildbucket for the build status
[19:43:40.557] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:43:40.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:42:0) after 1m0s
[19:44:40.721] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:42:0)
[19:44:40.721] Timer tick, asking Buildbucket for the build status
[19:44:41.087] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:44:41.087] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:43:0) after 1m0s
[19:45:41.110] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:43:0)
[19:45:41.110] Timer tick, asking Buildbucket for the build status
[19:45:41.257] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:45:41.258] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:44:0) after 1m0s
[19:46:41.354] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:44:0)
[19:46:41.354] Timer tick, asking Buildbucket for the build status
[19:46:41.477] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:46:41.477] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:45:0) after 1m0s
[19:47:41.507] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:45:0)
[19:47:41.507] Timer tick, asking Buildbucket for the build status
[19:47:41.855] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:47:41.855] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:46:0) after 1m0s
[19:48:41.874] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:46:0)
[19:48:41.874] Timer tick, asking Buildbucket for the build status
[19:48:41.962] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:48:41.962] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:47:0) after 1m0s
[19:49:41.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:47:0)
[19:49:41.979] Timer tick, asking Buildbucket for the build status
[19:49:42.114] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:49:42.114] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:48:0) after 1m0s
[19:50:42.201] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:48:0)
[19:50:42.201] Timer tick, asking Buildbucket for the build status
[19:50:42.557] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:50:42.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:49:0) after 1m0s
[19:51:43.408] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:49:0)
[19:51:43.408] Timer tick, asking Buildbucket for the build status
[19:51:43.999] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:51:43.999] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:50:0) after 1m0s
[19:52:44.066] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:50:0)
[19:52:44.066] Timer tick, asking Buildbucket for the build status
[19:52:44.668] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:52:44.669] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:51:0) after 1m0s
[19:53:44.685] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:51:0)
[19:53:44.685] Timer tick, asking Buildbucket for the build status
[19:53:44.953] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:53:44.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:52:0) after 1m0s
[19:54:44.990] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:52:0)
[19:54:44.990] Timer tick, asking Buildbucket for the build status
[19:54:45.554] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:54:45.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:53:0) after 1m0s
[19:55:45.591] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:53:0)
[19:55:45.591] Timer tick, asking Buildbucket for the build status
[19:55:45.840] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:55:45.840] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:54:0) after 1m0s
[19:56:45.882] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:54:0)
[19:56:45.882] Timer tick, asking Buildbucket for the build status
[19:56:45.992] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:56:45.992] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:55:0) after 1m0s
[19:57:46.005] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:55:0)
[19:57:46.005] Timer tick, asking Buildbucket for the build status
[19:57:46.362] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:57:46.362] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:56:0) after 1m0s
[19:58:46.389] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:56:0)
[19:58:46.389] Timer tick, asking Buildbucket for the build status
[19:58:46.993] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:58:46.993] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:57:0) after 1m0s
[19:59:47.031] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:57:0)
[19:59:47.031] Timer tick, asking Buildbucket for the build status
[19:59:48.769] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[19:59:48.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:58:0) after 1m0s
[20:00:48.818] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:58:0)
[20:00:48.818] Timer tick, asking Buildbucket for the build status
[20:00:48.953] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:00:48.953] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:59:0) after 1m0s
[20:01:49.004] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:59:0)
[20:01:49.004] Timer tick, asking Buildbucket for the build status
[20:01:49.312] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:01:49.312] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:60:0) after 1m0s
[20:02:49.407] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:60:0)
[20:02:49.407] Timer tick, asking Buildbucket for the build status
[20:02:49.525] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:02:49.525] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:61:0) after 1m0s
[20:03:49.546] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:61:0)
[20:03:49.546] Timer tick, asking Buildbucket for the build status
[20:03:49.750] Build 8856019933440277600: status "STARTED", result "", failure_reason "", cancelation_reason ""
[20:03:49.750] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021449995471993856:62:0) after 1m0s
[20:04:47.440] Received PubSub notification, asking Buildbucket for the build status
[20:04:47.931] Build 8856019933440277600: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[20:04:47.931] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612641885113559",
"created_by": "project:chromium",
"created_ts": "1612638266104707",
"id": "8856019933440277600",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"7f594365046a78c5a6032e4e07d4d3def3bbcc50\"}}",
"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\": \"7f594365046a78c5a6032e4e07d4d3def3bbcc50\", \"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-7-1hzs\", \"branch\": \"refs/heads/master\", \"builder_group\": \"chromium.memory\", \"buildername\": \"win-asan\", \"buildnumber\": 17202, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"d820ec7b3e5f9412d1ceb55d31b3f7dc09e3d16d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"7f594365046a78c5a6032e4e07d4d3def3bbcc50\", \"got_revision_cp\": \"refs/heads/master@{#851490}\", \"got_swarming_client_revision\": \"a32a1607f6093d338f756c7e7c7b4333b0c50c9c\", \"got_swiftshader_revision\": \"266614a36dbd5328a9079ea6bbef9026c7bbe3d7\", \"got_v8_revision\": \"711403215714087a74f05b1382b18e4c318be936\", \"got_v8_revision_cp\": \"refs/heads/9.0.137@{#1}\", \"got_webrtc_revision\": \"d42413a4b4636422ba5ea66ba40e7d24fb64f9b1\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#33183}\", \"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\": \"7f594365046a78c5a6032e4e07d4d3def3bbcc50\", \"swarm_hashes_refs/heads/master(at){#851490}_without_patch\": {\"absl_hardening_tests\": \"2f8399d60fdc164c12d7640d5833167a68be85d7\", \"accessibility_unittests\": \"17762343609ca77f64b6d634c04c6a7cc728c0b9\", \"angle_unittests\": \"ce3d4b7e826cd59bba78a9bc206d42a22908f7e4\", \"app_shell_unittests\": \"135c1ea6484fe4dcb728a0a0194a27721bf1b412\", \"aura_unittests\": \"f324f31163532a4dbae6f8cde8bbae308abeb822\", \"base_unittests\": \"8e82ec02a9d4aabad00e8be3645409eabe7db316\", \"base_util_unittests\": \"e0fbe8139789c061712ada98ff7094b73e10a86d\", \"blink_common_unittests\": \"087097d915475fb7d1490dd39b948f5d11af096a\", \"blink_fuzzer_unittests\": \"72380300a7ac744f18779e594826fc33431f1a06\", \"blink_heap_unittests\": \"acb132d9a3e7303336605444debcb2b4c7532785\", \"blink_platform_unittests\": \"f51e913c2e10c5eca0131503db37ab419df31c2d\", \"blink_unittests\": \"ed90d6fe510e8609b81e3bf1848b978e1b710533\", \"boringssl_crypto_tests\": \"abe1fdbd3803ac5318a41c3b61587dcc0b91a829\", \"boringssl_ssl_tests\": \"41911704fdab1339822244a682545cedbda02b57\", \"browser_switcher_bho_unittests\": \"08c334ea0f693f1f44fd7a5ba71331f0b05ecf1f\", \"browser_tests\": \"4a315f1fdbd0b50b9624dbea0e3528d622ff8518\", \"capture_unittests\": \"f3e2b74ff132160ce45399a62e3b93724656cf63\", \"cast_unittests\": \"76e3180cf216965c0772c7fe831bb5821ee97df4\", \"cc_unittests\": \"883013b93fb569c2a5b546dc78d4ed3a4f201a7d\", \"chrome_app_unittests\": \"8b25ccf0f0f29a2814e9b6d9c251435303da1904\", \"chrome_cleaner_unittests\": \"456429e49ee96e3e24c1645528bcf4f7834dc0f9\", \"chrome_elf_unittests\": \"bfa78ae4a1316ee9fd91f6e41db4e7894dae8eb3\", \"chromedriver_unittests\": \"507532f0c3d701a3f9a6ab3533c0f1404e2d83f1\", \"components_browsertests\": \"221e95f5420b724ef6d4bdec5c8c4e4803846b03\", \"components_unittests\": \"8ba882f8ba838e2dd94551d6eaca67313357709d\", \"compositor_unittests\": \"39d97ff77cbf99b63cd69ddc42dc4e9737eca39e\", \"content_browsertests\": \"a1c5fb1a168c2139cb85d0b8519b9348c341f0c9\", \"content_unittests\": \"407be8b603f23c3c629dcbeb2d7edc2df15a851c\", \"courgette_unittests\": \"5cbf4c11f974c2a14dff1e27e304fbd037541db9\", \"crashpad_tests\": \"1f04e0ef58248a7277e2621011e85ecffe49732e\", \"cronet_tests\": \"a6128eb94da720a5c2bdb38a6f7e4a7825a14c3f\", \"cronet_unittests\": \"d34a128dae08fee36e8c8d52659c1bcea5675846\", \"crypto_unittests\": \"0cabc2b7759f1a711040a7fbe728e53082f5100f\", \"delayloads_unittests\": \"2815995912432708838e440ef414ed4d3ae0eec9\", \"device_unittests\": \"f179a30dd7dcbbd5a2f4f553edd8721deb91c44c\", \"display_unittests\": \"6d306079cfa99267fb1670fbc379583a90dcef5b\", \"elevation_service_unittests\": \"6e77ccf326710534a55a6bf2d0ee3f3043c017df\", \"events_unittests\": \"fb386eefc972542ed9a069f7b8ef3237eda518ae\", \"extensions_browsertests\": \"5e1dc8f11494d099e940598220306fcb95915204\", \"extensions_unittests\": \"de3c7307b82f20fc25ea457f0e6b180959c2fab3\", \"filesystem_service_unittests\": \"c734b957c4a7b0583d855a52c63a243fd85efa1f\", \"gcm_unit_tests\": \"9ebdbb0ebb137b4833f38a2a6d94ea68f4984f3b\", \"gcp_unittests\": \"3f7c46e49d34fe9d627c979fd4fd3f4f76f11b9c\", \"gfx_unittests\": \"b6bfde62b8b63291af0944794c21a1ffc0152dfc\", \"gin_unittests\": \"d3222e9e3cbf85fcb1ecee854b80429620d9aa34\", \"google_apis_unittests\": \"22dc731f138518f2942a6c8bff45f30377d49509\", \"gpu_unittests\": \"f772600731c15c916242d50153fb0c00ab5c42bb\", \"gwp_asan_unittests\": \"1c6843dbd12f9ea743e42676a716a314bcc3f1a3\", \"headless_browsertests\": \"a75c08be36c183edea5a4a3e649193ff197f1c5f\", \"headless_unittests\": \"6952bc07f7fe98f8bfa251d711210f7e0608137a\", \"install_static_unittests\": \"273e174245e9efbab9032122f9138fa8a0611dc8\", \"installer_util_unittests\": \"e27317c511279c300b3e6756c7a7ac6aea16d64e\", \"interactive_ui_tests\": \"33d4a4510078f393b079b4510180923b4dad3c76\", \"ipc_tests\": \"f89634e4ff50f4a050f05c82bdaec58320a230bb\", \"jingle_unittests\": \"749285d9bce8acbd81fe45e916ee3f810873adda\", \"latency_unittests\": \"43614fb9f97acc74e37adb4200d209db967eb692\", \"libjingle_xmpp_unittests\": \"d2e5764e4ead9675e2ae2e7ec14f5ae2169399ba\", \"liburlpattern_unittests\": \"9a69247609a880e7d03a21a30d6a9fe66f836dd3\", \"media_blink_unittests\": \"a58f7af90c89d6803d00fd8c5fc040b3bd302482\", \"media_unittests\": \"47157da8a29610b7576b4f771322e77434de695a\", \"message_center_unittests\": \"9f1d3787b83f983db7cb0c022f28bacb9fbd69fd\", \"midi_unittests\": \"fe6e1e1b285b4ecfa919e72b24f0b0a40146dd8c\", \"mojo_core_unittests\": \"3f35e23b9ed881628f2ffce8aedadbc5d93325dd\", \"mojo_unittests\": \"09ac6cb4557649a8df32f98ff3355121a9871302\", \"nacl_loader_unittests\": \"28d874f94224d108a4033f1688157a540204d59d\", \"native_theme_unittests\": \"02205a699c74b943bf335301b1dd7c5b3e6ebc1b\", \"net_unittests\": \"0b0cfd65e69d8be7b78002fbcaa57b26f315c22a\", \"notification_helper_unittests\": \"79a5161c4f3b6ef5ff08c1ef0b5ea1589d6c29a9\", \"pdf_unittests\": \"99be7e47810cf8ac5185db23859786c49cc64448\", \"perfetto_unittests\": \"6e4864f4bb490d5a8228fb8737ef493bb2a9bcc7\", \"ppapi_unittests\": \"d1c1fce5be6e3c88b31e85912b1261a2ef5d8d1d\", \"printing_unittests\": \"aa8df9785d998b7b0a7a0173804fe8f850a9445c\", \"remoting_unittests\": \"1db0cad5eeabbc17665ebc8ef9004f133415b798\", \"sbox_integration_tests\": \"c2a579e91fe7b634ee8bdfb5d5cf41651be8f2a2\", \"sbox_unittests\": \"9d6712a91892336b6b88637176803ecdba66c737\", \"sbox_validation_tests\": \"ea03ef90178524e5aef4235567d963a2e54503cd\", \"service_manager_unittests\": \"1eddb422527137670355461ddb16b358aa0b3063\", \"services_unittests\": \"71bafec4786202a4e8a0929babddb7a286e8b580\", \"setup_unittests\": \"8f6b4424ce499ea64463fb56a198e143a49dfa3d\", \"shell_dialogs_unittests\": \"32be0ee73461be09adfff0297d4eea89bef40317\", \"skia_unittests\": \"ee281b7a1079974a809585663410775cb242e127\", \"snapshot_unittests\": \"c7e232eea22f6446930274d7ab806c10049d2654\", \"sql_unittests\": \"e84560be6ed2f4fbb313723472ef8b7eb8e75e31\", \"storage_unittests\": \"829392f6244ccca90a404f405f8e7d5be6cb9fc7\", \"sync_integration_tests\": \"5dd4d444bf8fad7561c7994e0469d3a6ff331b93\", \"traffic_annotation_auditor_unittests\": \"f5e7d5512cc10e7fccb3f235c9035dd5b435bea2\", \"ui_base_unittests\": \"27b20fd0e2da87ebd70f3a65c234b476d7c5874f\", \"ui_touch_selection_unittests\": \"dd72762fe332b0086d6a9fcd8970f1a4ec0eb65e\", \"unit_tests\": \"734f86fb4be5cb37cc0fb03098d968c23e40e16b\", \"updater_tests\": \"2015dbfe8b7210e525425c02ea713ec31a7fdf62\", \"url_unittests\": \"ce149c257912cd28ccecca7d90cca1cde2b94129\", \"views_unittests\": \"2a438d123dba04f46c5717f93a6e686ae9af0113\", \"viz_unittests\": \"8e38e1191a8bed49487d8cfd1f0d153d016d2793\", \"vr_common_unittests\": \"b79a85c9f8935ba9fe5a1ba1ff531ecc66bc6614\", \"vr_pixeltests\": \"59133cd50ec0ed4839d9ea2a931fc243ad711fa8\", \"weblayer_browsertests\": \"125bec69d63bd249c0749a6611b23a3dea36a707\", \"weblayer_unittests\": \"853755cad30db8577a02630cd739ac9d4480624a\", \"wm_unittests\": \"8244152efc4d57c91941b351c0c4749424678a38\", \"wtf_unittests\": \"23d04e38d3e3e5d710218f77f5b3cf001af7b673\", \"zlib_unittests\": \"d2c329ce0d677743671bda2c0943bcc8d9fcfd1f\", \"zucchini_unittests\": \"7fb8b1dc004c006cb3e82ab353f8492a5d0a09a3\"}}, \"swarming\": {\"bot_dimensions\": {\"builderless\": [\"1\"], \"caches\": [\"builder_27a86a15ec8feb386a5b02e9d1f544a49194bc134f7348519b0bd6cd38b20371_v2\", \"builder_c244a7c1904bb59c4b6cd021ade0aa3730bbe3d07abc076b32cf6cdc63527337_v2\", \"git\", \"goma_v2\", \"vpython\"], \"cores\": [\"32\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\"], \"gce\": [\"1\"], \"gcp\": [\"google.com:chromecompute\"], \"gpu\": [\"none\"], \"id\": [\"luci-chromium-ci-win10-32-7-1hzs\"], \"image\": [\"chrome-win10-1703-20111600-a937a23cefb\"], \"integrity\": [\"high\"], \"locale\": [\"en_US.cp1252\"], \"machine_type\": [\"e2-standard-32\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"pool\": [\"luci.chromium.ci\"], \"python\": [\"2.7.13\"], \"server_version\": [\"5676-c76598c\"], \"ssd\": [\"0\"], \"windows_client_version\": [\"10\"], \"zone\": [\"us\", \"us-west\", \"us-west1\", \"us-west1-b\"]}}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1612638272369400",
"status": "COMPLETED",
"status_changed_ts": "1612641885646239",
"tags": [
"build_address:luci.chromium.ci/win-asan/17202",
"builder:win-asan",
"buildset:commit/git/7f594365046a78c5a6032e4e07d4d3def3bbcc50",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/7f594365046a78c5a6032e4e07d4d3def3bbcc50",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021449995471993856",
"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/8856019933440277600/+/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:51918cb19bfe9310",
"user_agent:luci-scheduler"
],
"updated_ts": "1612641885704248",
"url": "https://ci.chromium.org/b/8856019933440277600",
"utcnow_ts": "1612641887920616"
}
}
[20:04:47.931] Invocation finished in 1h0m23.148555004s with status SUCCEEDED