[17:04:22.290] New invocation is queued and will start shortly
[17:04:23.466] Starting the invocation (attempt 1)
[17:04:23.498] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:04:23.499] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:04:23.499] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9021457547849138176",
"parameters_json": "{\"builder_name\":\"win-asan\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src\",\"revision\":\"166e7068a86a778a806a059b3223aaab1474897f\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:win-asan",
"scheduler_invocation_id:9021457547849138176",
"scheduler_job_id:chromium/win-asan",
"user_agent:luci-scheduler",
"buildset:commit/git/166e7068a86a778a806a059b3223aaab1474897f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/166e7068a86a778a806a059b3223aaab1474897f",
"gitiles_ref:refs/heads/master"
]
}
[17:04:24.097] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"created_by": "project:chromium",
"created_ts": "1612631063575239",
"id": "8856027485839059360",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"166e7068a86a778a806a059b3223aaab1474897f\"}}",
"project": "chromium",
"result_details_json": "{\"properties\": {}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1612631063984978",
"tags": [
"build_address:luci.chromium.ci/win-asan/17200",
"builder:win-asan",
"buildset:commit/git/166e7068a86a778a806a059b3223aaab1474897f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/166e7068a86a778a806a059b3223aaab1474897f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021457547849138176",
"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/8856027485839059360/+/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": "1612631063985093",
"url": "https://ci.chromium.org/b/8856027485839059360",
"utcnow_ts": "1612631064089597"
}
}
[17:04:24.097] Task URL: https://ci.chromium.org/b/8856027485839059360
[17:04:24.097] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:2:0) after 1m0s
[17:04:33.896] Received PubSub notification, asking Buildbucket for the build status
[17:04:34.380] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:24.068] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:2:0)
[17:05:24.068] Timer tick, asking Buildbucket for the build status
[17:05:24.176] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:05:24.176] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:4:0) after 1m0s
[17:06:24.208] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:4:0)
[17:06:24.208] Timer tick, asking Buildbucket for the build status
[17:06:25.429] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:06:25.429] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:5:0) after 1m0s
[17:07:25.473] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:5:0)
[17:07:25.473] Timer tick, asking Buildbucket for the build status
[17:07:25.863] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:07:25.863] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:6:0) after 1m0s
[17:08:25.887] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:6:0)
[17:08:25.887] Timer tick, asking Buildbucket for the build status
[17:08:26.000] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:08:26.000] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:7:0) after 1m0s
[17:09:25.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:7:0)
[17:09:25.998] Timer tick, asking Buildbucket for the build status
[17:09:26.152] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:09:26.152] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:8:0) after 1m0s
[17:10:26.206] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:8:0)
[17:10:26.206] Timer tick, asking Buildbucket for the build status
[17:10:26.729] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:10:26.729] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:9:0) after 1m0s
[17:11:26.822] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:9:0)
[17:11:26.822] Timer tick, asking Buildbucket for the build status
[17:11:27.197] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:11:27.197] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:10:0) after 1m0s
[17:12:27.215] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:10:0)
[17:12:27.215] Timer tick, asking Buildbucket for the build status
[17:12:27.275] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:12:27.275] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:11:0) after 1m0s
[17:13:27.454] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:11:0)
[17:13:27.454] Timer tick, asking Buildbucket for the build status
[17:13:27.554] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:13:27.554] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:12:0) after 1m0s
[17:14:27.989] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:12:0)
[17:14:27.989] Timer tick, asking Buildbucket for the build status
[17:14:28.094] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:14:28.094] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:13:0) after 1m0s
[17:15:28.152] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:13:0)
[17:15:28.152] Timer tick, asking Buildbucket for the build status
[17:15:28.253] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:15:28.253] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:14:0) after 1m0s
[17:16:28.548] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:14:0)
[17:16:28.548] Timer tick, asking Buildbucket for the build status
[17:16:28.768] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:16:28.769] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:15:0) after 1m0s
[17:17:28.986] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:15:0)
[17:17:28.986] Timer tick, asking Buildbucket for the build status
[17:17:29.046] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:17:29.046] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:16:0) after 1m0s
[17:18:29.165] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:16:0)
[17:18:29.165] Timer tick, asking Buildbucket for the build status
[17:18:29.231] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:18:29.231] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:17:0) after 1m0s
[17:19:29.449] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:17:0)
[17:19:29.449] Timer tick, asking Buildbucket for the build status
[17:19:29.623] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:19:29.623] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:18:0) after 1m0s
[17:20:29.740] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:18:0)
[17:20:29.740] Timer tick, asking Buildbucket for the build status
[17:20:29.794] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:20:29.794] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:19:0) after 1m0s
[17:21:29.979] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:19:0)
[17:21:29.979] Timer tick, asking Buildbucket for the build status
[17:21:30.117] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:21:30.117] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:20:0) after 1m0s
[17:22:30.104] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:20:0)
[17:22:30.104] Timer tick, asking Buildbucket for the build status
[17:22:30.330] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:22:30.330] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:21:0) after 1m0s
[17:23:30.451] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:21:0)
[17:23:30.451] Timer tick, asking Buildbucket for the build status
[17:23:30.919] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:23:30.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:22:0) after 1m0s
[17:24:30.947] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:22:0)
[17:24:30.948] Timer tick, asking Buildbucket for the build status
[17:24:31.906] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:24:31.906] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:23:0) after 1m0s
[17:25:31.964] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:23:0)
[17:25:31.964] Timer tick, asking Buildbucket for the build status
[17:25:32.130] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:25:32.130] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:24:0) after 1m0s
[17:26:32.119] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:24:0)
[17:26:32.119] Timer tick, asking Buildbucket for the build status
[17:26:32.243] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:26:32.243] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:25:0) after 1m0s
[17:27:32.285] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:25:0)
[17:27:32.285] Timer tick, asking Buildbucket for the build status
[17:27:32.474] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:27:32.474] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:26:0) after 1m0s
[17:28:32.497] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:26:0)
[17:28:32.497] Timer tick, asking Buildbucket for the build status
[17:28:32.708] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:28:32.708] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:27:0) after 1m0s
[17:29:32.829] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:27:0)
[17:29:32.829] Timer tick, asking Buildbucket for the build status
[17:29:32.981] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:29:32.981] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:28:0) after 1m0s
[17:30:33.047] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:28:0)
[17:30:33.047] Timer tick, asking Buildbucket for the build status
[17:30:33.208] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:30:33.208] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:29:0) after 1m0s
[17:31:33.243] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:29:0)
[17:31:33.243] Timer tick, asking Buildbucket for the build status
[17:31:33.354] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:31:33.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:30:0) after 1m0s
[17:32:33.438] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:30:0)
[17:32:33.438] Timer tick, asking Buildbucket for the build status
[17:32:33.557] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:32:33.557] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:31:0) after 1m0s
[17:33:33.665] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:31:0)
[17:33:33.665] Timer tick, asking Buildbucket for the build status
[17:33:33.860] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:33:33.860] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:32:0) after 1m0s
[17:34:33.907] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:32:0)
[17:34:33.907] Timer tick, asking Buildbucket for the build status
[17:34:34.013] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:34:34.013] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:33:0) after 1m0s
[17:35:34.048] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:33:0)
[17:35:34.048] Timer tick, asking Buildbucket for the build status
[17:35:34.512] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:35:34.512] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:34:0) after 1m0s
[17:36:34.620] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:34:0)
[17:36:34.620] Timer tick, asking Buildbucket for the build status
[17:36:34.739] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:36:34.739] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:35:0) after 1m0s
[17:37:34.794] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:35:0)
[17:37:34.794] Timer tick, asking Buildbucket for the build status
[17:37:34.872] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:34.872] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:36:0) after 1m0s
[17:38:34.957] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:36:0)
[17:38:34.957] Timer tick, asking Buildbucket for the build status
[17:38:35.354] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:35.354] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:37:0) after 1m0s
[17:39:35.485] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:37:0)
[17:39:35.485] Timer tick, asking Buildbucket for the build status
[17:39:35.849] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:35.849] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:38:0) after 1m0s
[17:40:35.943] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:38:0)
[17:40:35.943] Timer tick, asking Buildbucket for the build status
[17:40:36.034] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:36.034] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:39:0) after 1m0s
[17:41:36.123] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:39:0)
[17:41:36.123] Timer tick, asking Buildbucket for the build status
[17:41:36.538] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:36.538] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:40:0) after 1m0s
[17:42:36.693] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:40:0)
[17:42:36.694] Timer tick, asking Buildbucket for the build status
[17:42:36.912] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:36.912] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:41:0) after 1m0s
[17:43:36.929] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:41:0)
[17:43:36.929] Timer tick, asking Buildbucket for the build status
[17:43:37.057] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:37.057] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:42:0) after 1m0s
[17:44:37.177] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:42:0)
[17:44:37.177] Timer tick, asking Buildbucket for the build status
[17:44:38.126] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:38.126] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:43:0) after 1m0s
[17:45:38.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:43:0)
[17:45:38.183] Timer tick, asking Buildbucket for the build status
[17:45:38.472] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:38.472] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:44:0) after 1m0s
[17:46:38.527] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:44:0)
[17:46:38.527] Timer tick, asking Buildbucket for the build status
[17:46:38.689] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:38.689] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:45:0) after 1m0s
[17:47:38.784] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:45:0)
[17:47:38.784] Timer tick, asking Buildbucket for the build status
[17:47:38.848] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:38.848] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:46:0) after 1m0s
[17:48:38.819] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:46:0)
[17:48:38.819] Timer tick, asking Buildbucket for the build status
[17:48:39.211] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:39.211] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:47:0) after 1m0s
[17:49:39.257] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:47:0)
[17:49:39.257] Timer tick, asking Buildbucket for the build status
[17:49:39.797] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:39.797] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:48:0) after 1m0s
[17:50:39.775] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:48:0)
[17:50:39.775] Timer tick, asking Buildbucket for the build status
[17:50:39.967] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:39.967] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:49:0) after 1m0s
[17:51:39.998] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:49:0)
[17:51:39.998] Timer tick, asking Buildbucket for the build status
[17:51:40.132] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:40.132] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:50:0) after 1m0s
[17:52:40.223] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:50:0)
[17:52:40.223] Timer tick, asking Buildbucket for the build status
[17:52:40.407] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:40.407] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:51:0) after 1m0s
[17:53:40.469] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:51:0)
[17:53:40.469] Timer tick, asking Buildbucket for the build status
[17:53:41.095] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:41.095] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:52:0) after 1m0s
[17:54:41.183] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:52:0)
[17:54:41.183] Timer tick, asking Buildbucket for the build status
[17:54:41.345] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:41.345] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:53:0) after 1m0s
[17:55:41.319] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:53:0)
[17:55:41.319] Timer tick, asking Buildbucket for the build status
[17:55:41.639] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:41.639] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:54:0) after 1m0s
[17:56:41.656] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:54:0)
[17:56:41.656] Timer tick, asking Buildbucket for the build status
[17:56:41.745] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:41.745] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:55:0) after 1m0s
[17:57:41.765] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:55:0)
[17:57:41.765] Timer tick, asking Buildbucket for the build status
[17:57:41.919] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:41.919] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:56:0) after 1m0s
[17:58:41.946] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:56:0)
[17:58:41.946] Timer tick, asking Buildbucket for the build status
[17:58:42.062] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:42.062] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:57:0) after 1m0s
[17:59:42.148] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:57:0)
[17:59:42.148] Timer tick, asking Buildbucket for the build status
[17:59:42.283] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:42.283] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:58:0) after 1m0s
[18:00:42.276] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:58:0)
[18:00:42.276] Timer tick, asking Buildbucket for the build status
[18:00:42.479] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:42.479] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:59:0) after 1m0s
[18:01:42.565] Handling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:59:0)
[18:01:42.565] Timer tick, asking Buildbucket for the build status
[18:01:42.813] Build 8856027485839059360: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:42.813] Scheduling timer "check-buildbucket-build-status" (chromium/win-asan:9021457547849138176:60:0) after 1m0s
[18:02:38.286] Received PubSub notification, asking Buildbucket for the build status
[18:02:38.420] Build 8856027485839059360: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[18:02:38.420] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "PROD",
"completed_ts": "1612634556128258",
"created_by": "project:chromium",
"created_ts": "1612631063575239",
"id": "8856027485839059360",
"parameters_json": "{\"builder_name\": \"win-asan\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src\", \"revision\": \"166e7068a86a778a806a059b3223aaab1474897f\"}}",
"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\": \"166e7068a86a778a806a059b3223aaab1474897f\", \"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\": 17200, \"clang_revision\": \"llvmorg-13-init-794-g83e2710e-1\", \"got_angle_revision\": \"0bb520adffe41d0f2007c4fe7fdf5754d2939a1d\", \"got_dawn_revision\": \"2831f0584028114a8896059ce00e7bddaf3bba1f\", \"got_nacl_revision\": \"8db20f20316f13d8886a2732099f5bb880568663\", \"got_revision\": \"166e7068a86a778a806a059b3223aaab1474897f\", \"got_revision_cp\": \"refs/heads/master@{#851479}\", \"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\": \"166e7068a86a778a806a059b3223aaab1474897f\", \"swarm_hashes_refs/heads/master(at){#851479}_without_patch\": {\"absl_hardening_tests\": \"2f8399d60fdc164c12d7640d5833167a68be85d7\", \"accessibility_unittests\": \"b95743de53b82d034ad70e51b995e62148630dc4\", \"angle_unittests\": \"b97a5ad6c630f27bc360fdaf19892d00cfa95379\", \"app_shell_unittests\": \"ba27f30b6c554079b202c581c8e4fa011ecb4227\", \"aura_unittests\": \"d787e9c6a761f8fe791cf3fa5a1a5a22f916efa9\", \"base_unittests\": \"8e82ec02a9d4aabad00e8be3645409eabe7db316\", \"base_util_unittests\": \"e0fbe8139789c061712ada98ff7094b73e10a86d\", \"blink_common_unittests\": \"25ffd7f20606e4adeacab2d536e1a098a00b3ba7\", \"blink_fuzzer_unittests\": \"f9069c79f31c2f72a06b86281967f0595c29ed46\", \"blink_heap_unittests\": \"039f2d8501cf78dd20f9f287b98b87cd2c648490\", \"blink_platform_unittests\": \"c9735bddad8bb8374366b25c3650f972fc8972d6\", \"blink_unittests\": \"1cf69a87d8112ad97c20a223b2e2e831fa2c3919\", \"boringssl_crypto_tests\": \"abe1fdbd3803ac5318a41c3b61587dcc0b91a829\", \"boringssl_ssl_tests\": \"41911704fdab1339822244a682545cedbda02b57\", \"browser_switcher_bho_unittests\": \"08c334ea0f693f1f44fd7a5ba71331f0b05ecf1f\", \"browser_tests\": \"0f29d5a24fc9edb8118b020dac6d616ac1cd4f04\", \"capture_unittests\": \"10d4fe718fa6b0269e1cb453949df0ec7a79748b\", \"cast_unittests\": \"8a02b2abe8dfe3adf2a93833a8e6ec62c888e0fc\", \"cc_unittests\": \"7f03bffcaf4496fbd4178237a7ed62e7a09857a0\", \"chrome_app_unittests\": \"d894b00f4085b9f99d9b19afe4c8283a7b7d39ce\", \"chrome_cleaner_unittests\": \"1f9141eec2ef0803d589e89bcc7dff7ab693af87\", \"chrome_elf_unittests\": \"afa42476b3a7c300748491052f32a9b083d237c2\", \"chromedriver_unittests\": \"6d2ca3883163041e045701fd02aefc7a57812884\", \"components_browsertests\": \"4c51383caa79217307598fac07765cb3c838c72e\", \"components_unittests\": \"32fe24195f34ee2ca0787338a9a861ee2e640e64\", \"compositor_unittests\": \"084e95e612b9b178030c5f3c092623462f4aa736\", \"content_browsertests\": \"49761dec24dac0a9567f2ddc6959410fe11101fd\", \"content_unittests\": \"6edf2ecc2a9824a8422abcdfb4b6547d600edc48\", \"courgette_unittests\": \"5cbf4c11f974c2a14dff1e27e304fbd037541db9\", \"crashpad_tests\": \"1f04e0ef58248a7277e2621011e85ecffe49732e\", \"cronet_tests\": \"a6128eb94da720a5c2bdb38a6f7e4a7825a14c3f\", \"cronet_unittests\": \"d34a128dae08fee36e8c8d52659c1bcea5675846\", \"crypto_unittests\": \"0cabc2b7759f1a711040a7fbe728e53082f5100f\", \"delayloads_unittests\": \"fa46cedbdc27d9fd4213477e9f7839b1287d409d\", \"device_unittests\": \"9df038a399f048816206059b3bb7dcf42e426f2f\", \"display_unittests\": \"7e8956d8c9b90dc9dc170ea4617030957206bdf7\", \"elevation_service_unittests\": \"732222c2888c6c142cf375f5b4f7b4bfa7b0ded9\", \"events_unittests\": \"5d833130314830bdc53a3e5614a3ba16a153492b\", \"extensions_browsertests\": \"8784a36da93f5ce48ab7d38d86a0b70916fd727d\", \"extensions_unittests\": \"b24351f401c0a7035fdbf37d320f00fc78f36a5e\", \"filesystem_service_unittests\": \"c734b957c4a7b0583d855a52c63a243fd85efa1f\", \"gcm_unit_tests\": \"4ff3f08873dd8551568f9f139221f409558deb1d\", \"gcp_unittests\": \"4e05bf874b079097fb84397aa2014d427099bc82\", \"gfx_unittests\": \"7c5e025a41e144e8c807a1f1a921773fd2c4ce22\", \"gin_unittests\": \"d3222e9e3cbf85fcb1ecee854b80429620d9aa34\", \"google_apis_unittests\": \"0d0d0cd6b509fff408026d76137a5ccdf5660767\", \"gpu_unittests\": \"bfef4f6731cb4f4bd820330794d2d5f62358265d\", \"gwp_asan_unittests\": \"1c6843dbd12f9ea743e42676a716a314bcc3f1a3\", \"headless_browsertests\": \"347873c50b081b0a10f5d80e64742d9a432e7ed8\", \"headless_unittests\": \"6a170db354ac0f5c2de00957b094ae9d383ab844\", \"install_static_unittests\": \"273e174245e9efbab9032122f9138fa8a0611dc8\", \"installer_util_unittests\": \"561d7b7c6a994d7fb36fbc56e08314360045ed53\", \"interactive_ui_tests\": \"497b8acfcc886540e2800bfbb4fa2344ef0d5d0c\", \"ipc_tests\": \"f89634e4ff50f4a050f05c82bdaec58320a230bb\", \"jingle_unittests\": \"70a2d09d465ae7889169572f0801c0315f315410\", \"latency_unittests\": \"08f070c9ac26e25f71895413d901f6c46d91206c\", \"libjingle_xmpp_unittests\": \"d2e5764e4ead9675e2ae2e7ec14f5ae2169399ba\", \"liburlpattern_unittests\": \"9a69247609a880e7d03a21a30d6a9fe66f836dd3\", \"media_blink_unittests\": \"0c152d9de5486df6d9b422a851ef026db0203320\", \"media_unittests\": \"78e4435606e076c8a7d866e66b89c308e3b6a771\", \"message_center_unittests\": \"0aab5e3be7f75b29d25e89419e2d6da8931c8e7e\", \"midi_unittests\": \"fe6e1e1b285b4ecfa919e72b24f0b0a40146dd8c\", \"mojo_core_unittests\": \"3f35e23b9ed881628f2ffce8aedadbc5d93325dd\", \"mojo_unittests\": \"9e5fc51114d8469781447d44cdf54cf8aaaea753\", \"nacl_loader_unittests\": \"cbb3282aa4bfece6c0ce0421080f5067824f8e65\", \"native_theme_unittests\": \"2ce794342df7774c49c61eb0b4cffa6ff4ff24a2\", \"net_unittests\": \"0b0cfd65e69d8be7b78002fbcaa57b26f315c22a\", \"notification_helper_unittests\": \"eac03bd49c10f3fa0af29d3c08f1982f45a137d0\", \"pdf_unittests\": \"5e401ca2cf6438e2e181b09fae8597d7e715ee23\", \"perfetto_unittests\": \"6e4864f4bb490d5a8228fb8737ef493bb2a9bcc7\", \"ppapi_unittests\": \"c4d9f796f918296963fe9070ca9e4ab986591bf8\", \"printing_unittests\": \"2d5353b1c573c4f67a4b27be65e4460440ef3960\", \"remoting_unittests\": \"dd299b8c6e4eff9f2a78342fde1be2ef6f767ddc\", \"sbox_integration_tests\": \"c2a579e91fe7b634ee8bdfb5d5cf41651be8f2a2\", \"sbox_unittests\": \"9d6712a91892336b6b88637176803ecdba66c737\", \"sbox_validation_tests\": \"ea03ef90178524e5aef4235567d963a2e54503cd\", \"service_manager_unittests\": \"135bff190f95fdedc8a9a5ba524d10a31331ed86\", \"services_unittests\": \"727b496bc3efdb3ca212a3e424d5a2a2322197cf\", \"setup_unittests\": \"a6ff4b5d87c2ac7f641e952d74b9fbcb0eecb186\", \"shell_dialogs_unittests\": \"51e59753953fdfb4447b6f82b52b092e68a688e4\", \"skia_unittests\": \"f9106166428cb751cd73358fae949a12f4ae9af3\", \"snapshot_unittests\": \"e20badaea154c004ee217be0da23aef2359d03c6\", \"sql_unittests\": \"e84560be6ed2f4fbb313723472ef8b7eb8e75e31\", \"storage_unittests\": \"fc4528a16c5cdc350e15428c6390acfe5f0767ac\", \"sync_integration_tests\": \"25b706a76508800f3cfe65b09650c64cbfe9dbe7\", \"traffic_annotation_auditor_unittests\": \"f5e7d5512cc10e7fccb3f235c9035dd5b435bea2\", \"ui_base_unittests\": \"3fc6f23c567869992bfb582abb9f9d594ac5e2ad\", \"ui_touch_selection_unittests\": \"c87668e5d59d5df9659540b8232afae13c76848f\", \"unit_tests\": \"602be975881a1aff88851bb1d02ca05108e2f14f\", \"updater_tests\": \"a1fbb65839097f4dc0b1d2f1caa68c7ed97f6130\", \"url_unittests\": \"ce149c257912cd28ccecca7d90cca1cde2b94129\", \"views_unittests\": \"77cbe09ab534ff72b304f09293161ef70ea90e1b\", \"viz_unittests\": \"40f7dcaed50656813d5019f51ca5f40c0c5f72b4\", \"vr_common_unittests\": \"62c73470004aa979fd225135342953912ae8dafb\", \"vr_pixeltests\": \"493f17da9171041f91214d420a0bb4d54cc71d99\", \"weblayer_browsertests\": \"4dd4b7e616b525ad8cc10a9e8015548564847e58\", \"weblayer_unittests\": \"ff63fd855d4bed8fb6930f85555a557a2c87cc0f\", \"wm_unittests\": \"1425b0dcca9a349a8d1051fa0b51511920684eab\", \"wtf_unittests\": \"9ca234cdb611ef101d5b2d43fdfb5f9015240139\", \"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": "1612631072128086",
"status": "COMPLETED",
"status_changed_ts": "1612634556866098",
"tags": [
"build_address:luci.chromium.ci/win-asan/17200",
"builder:win-asan",
"buildset:commit/git/166e7068a86a778a806a059b3223aaab1474897f",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/166e7068a86a778a806a059b3223aaab1474897f",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9021457547849138176",
"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/8856027485839059360/+/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:51911ecc7b9b6110",
"user_agent:luci-scheduler"
],
"updated_ts": "1612634556891917",
"url": "https://ci.chromium.org/b/8856027485839059360",
"utcnow_ts": "1612634558410820"
}
}
[18:02:38.420] Invocation finished in 58m16.146483287s with status SUCCEEDED